{"level":"warn","ts":"2026-02-06T00:36:49.896586Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-06T00:36:49.89675Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.206:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.206:2380","--initial-cluster=instance=https://162.253.55.206:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.206:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.206:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-06T00:36:49.89685Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-06T00:36:49.896878Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.206:2380"]} {"level":"info","ts":"2026-02-06T00:36:49.896908Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-06T00:36:49.897613Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"]} {"level":"info","ts":"2026-02-06T00:36:49.897745Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.206:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-06T00:36:49.903256Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.133059ms"} {"level":"info","ts":"2026-02-06T00:36:49.922161Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cfc9fec265e3384a","cluster-id":"9866fe2b0d49ed88"} {"level":"info","ts":"2026-02-06T00:36:49.922344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=()"} {"level":"info","ts":"2026-02-06T00:36:49.922408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 0"} {"level":"info","ts":"2026-02-06T00:36:49.922442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cfc9fec265e3384a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-06T00:36:49.922499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 1"} {"level":"info","ts":"2026-02-06T00:36:49.922573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"} {"level":"warn","ts":"2026-02-06T00:36:49.928502Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-06T00:36:49.930732Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-06T00:36:49.935627Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-06T00:36:49.945098Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cfc9fec265e3384a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-06T00:36:49.946121Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cfc9fec265e3384a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-06T00:36:49.946299Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-06T00:36:49.946377Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-06T00:36:49.946433Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-06T00:36:49.946724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"} {"level":"info","ts":"2026-02-06T00:36:49.947086Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","added-peer-id":"cfc9fec265e3384a","added-peer-peer-urls":["https://162.253.55.206:2380"]} {"level":"info","ts":"2026-02-06T00:36:49.960029Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-06T00:36:49.960695Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cfc9fec265e3384a","initial-advertise-peer-urls":["https://162.253.55.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-06T00:36:49.960812Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-06T00:36:49.960957Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.206:2380"} {"level":"info","ts":"2026-02-06T00:36:49.96102Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.206:2380"} {"level":"info","ts":"2026-02-06T00:36:50.822828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a is starting a new election at term 1"} {"level":"info","ts":"2026-02-06T00:36:50.823024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-06T00:36:50.823046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgPreVoteResp from cfc9fec265e3384a at term 1"} {"level":"info","ts":"2026-02-06T00:36:50.823058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became candidate at term 2"} {"level":"info","ts":"2026-02-06T00:36:50.82307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgVoteResp from cfc9fec265e3384a at term 2"} {"level":"info","ts":"2026-02-06T00:36:50.823078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became leader at term 2"} {"level":"info","ts":"2026-02-06T00:36:50.823085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cfc9fec265e3384a elected leader cfc9fec265e3384a at term 2"} {"level":"info","ts":"2026-02-06T00:36:50.82445Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:36:50.826073Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cfc9fec265e3384a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.206:2379]}","request-path":"/0/members/cfc9fec265e3384a/attributes","cluster-id":"9866fe2b0d49ed88","publish-timeout":"7s"} {"level":"info","ts":"2026-02-06T00:36:50.826246Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T00:36:50.826576Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T00:36:50.832598Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.206:2379"} {"level":"info","ts":"2026-02-06T00:36:50.826657Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:36:50.834005Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:36:50.834669Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:36:50.840203Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-06T00:36:50.851084Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-06T00:36:50.851147Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-06T00:36:52.841311Z","caller":"traceutil/trace.go:171","msg":"trace[1492276329] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"216.574322ms","start":"2026-02-06T00:36:52.624701Z","end":"2026-02-06T00:36:52.841275Z","steps":["trace[1492276329] 'process raft request' (duration: 151.634531ms)","trace[1492276329] 'compare' (duration: 64.560747ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:36:52.841931Z","caller":"traceutil/trace.go:171","msg":"trace[1322196141] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"156.237101ms","start":"2026-02-06T00:36:52.685662Z","end":"2026-02-06T00:36:52.8419Z","steps":["trace[1322196141] 'process raft request' (duration: 156.193799ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:52.842013Z","caller":"traceutil/trace.go:171","msg":"trace[333260913] transaction","detail":"{read_only:false; number_of_response:0; response_revision:13; }","duration":"217.245467ms","start":"2026-02-06T00:36:52.62476Z","end":"2026-02-06T00:36:52.842005Z","steps":["trace[333260913] 'process raft request' (duration: 216.349834ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:52.84221Z","caller":"traceutil/trace.go:171","msg":"trace[1030920905] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"214.9119ms","start":"2026-02-06T00:36:52.627289Z","end":"2026-02-06T00:36:52.842201Z","steps":["trace[1030920905] 'process raft request' (duration: 213.862191ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:52.842243Z","caller":"traceutil/trace.go:171","msg":"trace[694351130] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"214.897629ms","start":"2026-02-06T00:36:52.627338Z","end":"2026-02-06T00:36:52.842235Z","steps":["trace[694351130] 'process raft request' (duration: 213.967865ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:52.842274Z","caller":"traceutil/trace.go:171","msg":"trace[1508001631] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"214.886859ms","start":"2026-02-06T00:36:52.62738Z","end":"2026-02-06T00:36:52.842266Z","steps":["trace[1508001631] 'process raft request' (duration: 213.955474ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:52.842441Z","caller":"traceutil/trace.go:171","msg":"trace[2120633413] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"214.057898ms","start":"2026-02-06T00:36:52.628372Z","end":"2026-02-06T00:36:52.842429Z","steps":["trace[2120633413] 'process raft request' (duration: 212.990838ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:52.842648Z","caller":"traceutil/trace.go:171","msg":"trace[1695584648] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"214.221634ms","start":"2026-02-06T00:36:52.628417Z","end":"2026-02-06T00:36:52.842638Z","steps":["trace[1695584648] 'process raft request' (duration: 212.998169ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:52.842686Z","caller":"traceutil/trace.go:171","msg":"trace[525779789] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"213.121003ms","start":"2026-02-06T00:36:52.629556Z","end":"2026-02-06T00:36:52.842677Z","steps":["trace[525779789] 'process raft request' (duration: 212.179448ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:52.842717Z","caller":"traceutil/trace.go:171","msg":"trace[786861960] linearizableReadLoop","detail":"{readStateIndex:23; appliedIndex:15; }","duration":"181.676756ms","start":"2026-02-06T00:36:52.66103Z","end":"2026-02-06T00:36:52.842706Z","steps":["trace[786861960] 'read index received' (duration: 115.439567ms)","trace[786861960] 'applied index is now lower than readState.Index' (duration: 66.235669ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:36:52.842805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.029306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3179"} {"level":"info","ts":"2026-02-06T00:36:52.848731Z","caller":"traceutil/trace.go:171","msg":"trace[272039016] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:22; }","duration":"223.990627ms","start":"2026-02-06T00:36:52.624726Z","end":"2026-02-06T00:36:52.848717Z","steps":["trace[272039016] 'agreement among raft nodes before linearized reading' (duration: 218.010435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:52.848972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.479645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T00:36:52.849012Z","caller":"traceutil/trace.go:171","msg":"trace[758252609] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:22; }","duration":"182.536497ms","start":"2026-02-06T00:36:52.666462Z","end":"2026-02-06T00:36:52.848999Z","steps":["trace[758252609] 'agreement among raft nodes before linearized reading' (duration: 182.477615ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:52.842941Z","caller":"traceutil/trace.go:171","msg":"trace[1256592016] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"160.269921ms","start":"2026-02-06T00:36:52.682659Z","end":"2026-02-06T00:36:52.842929Z","steps":["trace[1256592016] 'process raft request' (duration: 159.122878ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:52.843006Z","caller":"traceutil/trace.go:171","msg":"trace[1113129427] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"160.25262ms","start":"2026-02-06T00:36:52.682736Z","end":"2026-02-06T00:36:52.842989Z","steps":["trace[1113129427] 'process raft request' (duration: 159.076947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:52.843227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.513497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-02-06T00:36:52.849637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.852039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T00:36:52.849725Z","caller":"traceutil/trace.go:171","msg":"trace[1886882647] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:22; }","duration":"169.039477ms","start":"2026-02-06T00:36:52.68067Z","end":"2026-02-06T00:36:52.849709Z","steps":["trace[1886882647] 'agreement among raft nodes before linearized reading' (duration: 168.832219ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:52.849597Z","caller":"traceutil/trace.go:171","msg":"trace[599842036] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:22; }","duration":"195.935495ms","start":"2026-02-06T00:36:52.653651Z","end":"2026-02-06T00:36:52.849587Z","steps":["trace[599842036] 'agreement among raft nodes before linearized reading' (duration: 189.541768ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:53.05717Z","caller":"traceutil/trace.go:171","msg":"trace[678728287] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"202.394682ms","start":"2026-02-06T00:36:52.854734Z","end":"2026-02-06T00:36:53.057129Z","steps":["trace[678728287] 'process raft request' (duration: 143.311471ms)","trace[678728287] 'compare' (duration: 58.77152ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:36:53.0692Z","caller":"traceutil/trace.go:171","msg":"trace[1760610744] linearizableReadLoop","detail":"{readStateIndex:28; appliedIndex:26; }","duration":"214.315406ms","start":"2026-02-06T00:36:52.854856Z","end":"2026-02-06T00:36:53.069172Z","steps":["trace[1760610744] 'read index received' (duration: 143.409405ms)","trace[1760610744] 'applied index is now lower than readState.Index' (duration: 70.904851ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:36:53.069407Z","caller":"traceutil/trace.go:171","msg":"trace[445315447] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"213.196174ms","start":"2026-02-06T00:36:52.856194Z","end":"2026-02-06T00:36:53.06939Z","steps":["trace[445315447] 'process raft request' (duration: 212.740797ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:53.069396Z","caller":"traceutil/trace.go:171","msg":"trace[774833935] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"214.487912ms","start":"2026-02-06T00:36:52.854767Z","end":"2026-02-06T00:36:53.069255Z","steps":["trace[774833935] 'process raft request' (duration: 214.039086ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:53.069795Z","caller":"traceutil/trace.go:171","msg":"trace[1324936493] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"210.192053ms","start":"2026-02-06T00:36:52.859592Z","end":"2026-02-06T00:36:53.069784Z","steps":["trace[1324936493] 'process raft request' (duration: 209.377143ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:53.070004Z","caller":"traceutil/trace.go:171","msg":"trace[1518245056] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"209.257478ms","start":"2026-02-06T00:36:52.860692Z","end":"2026-02-06T00:36:53.069949Z","steps":["trace[1518245056] 'process raft request' (duration: 208.403276ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:53.070537Z","caller":"traceutil/trace.go:171","msg":"trace[1732931370] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"209.926574ms","start":"2026-02-06T00:36:52.860595Z","end":"2026-02-06T00:36:53.070522Z","steps":["trace[1732931370] 'process raft request' (duration: 208.413467ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:53.070876Z","caller":"traceutil/trace.go:171","msg":"trace[1839613280] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"210.243815ms","start":"2026-02-06T00:36:52.860621Z","end":"2026-02-06T00:36:53.070865Z","steps":["trace[1839613280] 'process raft request' (duration: 208.419217ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:53.071214Z","caller":"traceutil/trace.go:171","msg":"trace[706018095] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"210.520536ms","start":"2026-02-06T00:36:52.860681Z","end":"2026-02-06T00:36:53.071202Z","steps":["trace[706018095] 'process raft request' (duration: 208.388276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:53.072041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.093503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-06T00:36:53.072172Z","caller":"traceutil/trace.go:171","msg":"trace[651277263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:31; }","duration":"209.244739ms","start":"2026-02-06T00:36:52.862914Z","end":"2026-02-06T00:36:53.072159Z","steps":["trace[651277263] 'agreement among raft nodes before linearized reading' (duration: 209.00576ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:53.072425Z","caller":"traceutil/trace.go:171","msg":"trace[1771150813] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"211.679879ms","start":"2026-02-06T00:36:52.860733Z","end":"2026-02-06T00:36:53.072413Z","steps":["trace[1771150813] 'process raft request' (duration: 208.388246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:53.072795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.932981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-06T00:36:53.072911Z","caller":"traceutil/trace.go:171","msg":"trace[1544455750] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:31; }","duration":"218.066186ms","start":"2026-02-06T00:36:52.854834Z","end":"2026-02-06T00:36:53.0729Z","steps":["trace[1544455750] 'agreement among raft nodes before linearized reading' (duration: 217.89391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:53.073208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.682404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T00:36:53.073447Z","caller":"traceutil/trace.go:171","msg":"trace[1357481575] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:31; }","duration":"136.948054ms","start":"2026-02-06T00:36:52.936486Z","end":"2026-02-06T00:36:53.073435Z","steps":["trace[1357481575] 'agreement among raft nodes before linearized reading' (duration: 136.658823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:53.073769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.819825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T00:36:53.073914Z","caller":"traceutil/trace.go:171","msg":"trace[785728484] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:31; }","duration":"162.95231ms","start":"2026-02-06T00:36:52.910901Z","end":"2026-02-06T00:36:53.073854Z","steps":["trace[785728484] 'agreement among raft nodes before linearized reading' (duration: 162.830085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:53.074133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.193741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-06T00:36:53.074222Z","caller":"traceutil/trace.go:171","msg":"trace[1900197718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:31; }","duration":"211.270604ms","start":"2026-02-06T00:36:52.862905Z","end":"2026-02-06T00:36:53.074176Z","steps":["trace[1900197718] 'agreement among raft nodes before linearized reading' (duration: 211.17926ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:53.702519Z","caller":"traceutil/trace.go:171","msg":"trace[279464975] linearizableReadLoop","detail":"{readStateIndex:67; appliedIndex:65; }","duration":"130.010224ms","start":"2026-02-06T00:36:53.572485Z","end":"2026-02-06T00:36:53.702496Z","steps":["trace[279464975] 'read index received' (duration: 28.228832ms)","trace[279464975] 'applied index is now lower than readState.Index' (duration: 101.780082ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:36:53.702849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.308555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T00:36:53.702905Z","caller":"traceutil/trace.go:171","msg":"trace[612255382] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:63; }","duration":"130.450431ms","start":"2026-02-06T00:36:53.572438Z","end":"2026-02-06T00:36:53.702889Z","steps":["trace[612255382] 'agreement among raft nodes before linearized reading' (duration: 130.15244ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:53.702555Z","caller":"traceutil/trace.go:171","msg":"trace[936193824] transaction","detail":"{read_only:false; response_revision:62; number_of_response:1; }","duration":"132.130274ms","start":"2026-02-06T00:36:53.570396Z","end":"2026-02-06T00:36:53.702526Z","steps":["trace[936193824] 'process raft request' (duration: 87.874725ms)","trace[936193824] 'compare' (duration: 44.056081ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:36:53.703288Z","caller":"traceutil/trace.go:171","msg":"trace[1081616380] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"128.871821ms","start":"2026-02-06T00:36:53.574402Z","end":"2026-02-06T00:36:53.703274Z","steps":["trace[1081616380] 'process raft request' (duration: 128.03457ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:53.873043Z","caller":"traceutil/trace.go:171","msg":"trace[1243200009] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"105.110697ms","start":"2026-02-06T00:36:53.767899Z","end":"2026-02-06T00:36:53.87301Z","steps":["trace[1243200009] 'process raft request' (duration: 71.070689ms)","trace[1243200009] 'compare' (duration: 33.435275ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:36:53.873975Z","caller":"traceutil/trace.go:171","msg":"trace[1064614940] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"105.7415ms","start":"2026-02-06T00:36:53.768221Z","end":"2026-02-06T00:36:53.873962Z","steps":["trace[1064614940] 'process raft request' (duration: 104.434592ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:53.874335Z","caller":"traceutil/trace.go:171","msg":"trace[1579518628] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:74; }","duration":"106.015291ms","start":"2026-02-06T00:36:53.768305Z","end":"2026-02-06T00:36:53.87432Z","steps":["trace[1579518628] 'read index received' (duration: 70.708645ms)","trace[1579518628] 'applied index is now lower than readState.Index' (duration: 35.305516ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:36:53.874867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.481678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18918138a4bd0dcf\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-02-06T00:36:53.875286Z","caller":"traceutil/trace.go:171","msg":"trace[1013382032] range","detail":"{range_begin:/registry/events/default/instance.18918138a4bd0dcf; range_end:; response_count:1; response_revision:72; }","duration":"106.980406ms","start":"2026-02-06T00:36:53.768295Z","end":"2026-02-06T00:36:53.875276Z","steps":["trace[1013382032] 'agreement among raft nodes before linearized reading' (duration: 106.222908ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:53.875213Z","caller":"traceutil/trace.go:171","msg":"trace[1234537793] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"106.934395ms","start":"2026-02-06T00:36:53.768266Z","end":"2026-02-06T00:36:53.8752Z","steps":["trace[1234537793] 'process raft request' (duration: 105.631606ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:54.857448Z","caller":"traceutil/trace.go:171","msg":"trace[1461209629] transaction","detail":"{read_only:false; response_revision:122; number_of_response:1; }","duration":"176.049522ms","start":"2026-02-06T00:36:54.681374Z","end":"2026-02-06T00:36:54.857424Z","steps":["trace[1461209629] 'process raft request' (duration: 175.98989ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:54.85797Z","caller":"traceutil/trace.go:171","msg":"trace[52625958] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"195.171488ms","start":"2026-02-06T00:36:54.662785Z","end":"2026-02-06T00:36:54.857956Z","steps":["trace[52625958] 'process raft request' (duration: 154.537049ms)","trace[52625958] 'compare' (duration: 39.909512ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:36:55.304233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.928887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:36:55.304631Z","caller":"traceutil/trace.go:171","msg":"trace[1097010735] linearizableReadLoop","detail":"{readStateIndex:138; appliedIndex:137; }","duration":"208.840994ms","start":"2026-02-06T00:36:55.095767Z","end":"2026-02-06T00:36:55.304608Z","steps":["trace[1097010735] 'read index received' (duration: 22.897589ms)","trace[1097010735] 'applied index is now lower than readState.Index' (duration: 185.941365ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:36:55.304643Z","caller":"traceutil/trace.go:171","msg":"trace[1225175403] transaction","detail":"{read_only:false; response_revision:133; number_of_response:1; }","duration":"251.428492ms","start":"2026-02-06T00:36:55.053171Z","end":"2026-02-06T00:36:55.304599Z","steps":["trace[1225175403] 'process raft request' (duration: 65.520568ms)","trace[1225175403] 'compare' (duration: 184.757811ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:36:55.305137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.244269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:36:55.305365Z","caller":"traceutil/trace.go:171","msg":"trace[2003398042] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient; range_end:; response_count:0; response_revision:133; }","duration":"209.464197ms","start":"2026-02-06T00:36:55.095764Z","end":"2026-02-06T00:36:55.305228Z","steps":["trace[2003398042] 'agreement among raft nodes before linearized reading' (duration: 209.157276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:55.305809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.777644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-06T00:36:55.305985Z","caller":"traceutil/trace.go:171","msg":"trace[1256155888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:133; }","duration":"199.976322ms","start":"2026-02-06T00:36:55.105996Z","end":"2026-02-06T00:36:55.305973Z","steps":["trace[1256155888] 'agreement among raft nodes before linearized reading' (duration: 199.766494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:55.306233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.212252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-06T00:36:55.30629Z","caller":"traceutil/trace.go:171","msg":"trace[236450237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:133; }","duration":"143.342377ms","start":"2026-02-06T00:36:55.162932Z","end":"2026-02-06T00:36:55.306275Z","steps":["trace[236450237] 'agreement among raft nodes before linearized reading' (duration: 143.190632ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:55.484252Z","caller":"traceutil/trace.go:171","msg":"trace[835876069] transaction","detail":"{read_only:false; response_revision:138; number_of_response:1; }","duration":"102.236185ms","start":"2026-02-06T00:36:55.381948Z","end":"2026-02-06T00:36:55.484184Z","steps":["trace[835876069] 'process raft request' (duration: 31.895637ms)","trace[835876069] 'compare' (duration: 70.195693ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:36:55.596331Z","caller":"traceutil/trace.go:171","msg":"trace[1905556679] linearizableReadLoop","detail":"{readStateIndex:145; appliedIndex:144; }","duration":"102.164591ms","start":"2026-02-06T00:36:55.494139Z","end":"2026-02-06T00:36:55.596304Z","steps":["trace[1905556679] 'read index received' (duration: 94.633819ms)","trace[1905556679] 'applied index is now lower than readState.Index' (duration: 7.529742ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:36:55.596767Z","caller":"traceutil/trace.go:171","msg":"trace[453982916] transaction","detail":"{read_only:false; response_revision:140; number_of_response:1; }","duration":"107.836184ms","start":"2026-02-06T00:36:55.488882Z","end":"2026-02-06T00:36:55.596718Z","steps":["trace[453982916] 'process raft request' (duration: 99.943449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:36:55.596823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.65469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:36:55.597614Z","caller":"traceutil/trace.go:171","msg":"trace[689162065] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver; range_end:; response_count:0; response_revision:140; }","duration":"103.437069ms","start":"2026-02-06T00:36:55.494101Z","end":"2026-02-06T00:36:55.597538Z","steps":["trace[689162065] 'agreement among raft nodes before linearized reading' (duration: 102.577877ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:55.933077Z","caller":"traceutil/trace.go:171","msg":"trace[1960643871] transaction","detail":"{read_only:false; response_revision:148; number_of_response:1; }","duration":"127.976711ms","start":"2026-02-06T00:36:55.805067Z","end":"2026-02-06T00:36:55.933044Z","steps":["trace[1960643871] 'process raft request' (duration: 91.539564ms)","trace[1960643871] 'compare' (duration: 36.269421ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:36:56.78194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.394123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:36:56.782063Z","caller":"traceutil/trace.go:171","msg":"trace[1211136736] transaction","detail":"{read_only:false; response_revision:168; number_of_response:1; }","duration":"217.083849ms","start":"2026-02-06T00:36:56.564956Z","end":"2026-02-06T00:36:56.78204Z","steps":["trace[1211136736] 'process raft request' (duration: 76.494689ms)","trace[1211136736] 'compare' (duration: 140.151504ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:36:56.997362Z","caller":"traceutil/trace.go:171","msg":"trace[1631922984] transaction","detail":"{read_only:false; response_revision:172; number_of_response:1; }","duration":"133.547216ms","start":"2026-02-06T00:36:56.86378Z","end":"2026-02-06T00:36:56.997327Z","steps":["trace[1631922984] 'process raft request' (duration: 67.528022ms)","trace[1631922984] 'compare' (duration: 65.873779ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:36:57.221856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.535243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:36:57.222214Z","caller":"traceutil/trace.go:171","msg":"trace[1672686149] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"137.730649ms","start":"2026-02-06T00:36:57.084463Z","end":"2026-02-06T00:36:57.222194Z","steps":["trace[1672686149] 'process raft request' (duration: 22.133125ms)","trace[1672686149] 'compare' (duration: 114.330376ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:36:57.588871Z","caller":"traceutil/trace.go:171","msg":"trace[1188851148] linearizableReadLoop","detail":"{readStateIndex:187; appliedIndex:186; }","duration":"171.429241ms","start":"2026-02-06T00:36:57.417421Z","end":"2026-02-06T00:36:57.58885Z","steps":["trace[1188851148] 'read index received' (duration: 19.732705ms)","trace[1188851148] 'applied index is now lower than readState.Index' (duration: 151.695536ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:36:57.589011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.556586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:discovery\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:36:57.58904Z","caller":"traceutil/trace.go:171","msg":"trace[1336367213] range","detail":"{range_begin:/registry/clusterrolebindings/system:discovery; range_end:; response_count:0; response_revision:182; }","duration":"171.628698ms","start":"2026-02-06T00:36:57.417404Z","end":"2026-02-06T00:36:57.589033Z","steps":["trace[1336367213] 'agreement among raft nodes before linearized reading' (duration: 171.522924ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:36:57.589445Z","caller":"traceutil/trace.go:171","msg":"trace[557728085] transaction","detail":"{read_only:false; response_revision:182; number_of_response:1; }","duration":"171.93178ms","start":"2026-02-06T00:36:57.417368Z","end":"2026-02-06T00:36:57.5893Z","steps":["trace[557728085] 'process raft request' (duration: 124.932876ms)","trace[557728085] 'compare' (duration: 46.453104ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:37:04.707671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.798268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:37:04.707833Z","caller":"traceutil/trace.go:171","msg":"trace[818029325] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:362; }","duration":"188.567832ms","start":"2026-02-06T00:37:04.519239Z","end":"2026-02-06T00:37:04.707807Z","steps":["trace[818029325] 'read index received' (duration: 36.207242ms)","trace[818029325] 'applied index is now lower than readState.Index' (duration: 152.35898ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:37:04.707962Z","caller":"traceutil/trace.go:171","msg":"trace[1416449836] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"195.849373ms","start":"2026-02-06T00:37:04.51209Z","end":"2026-02-06T00:37:04.707939Z","steps":["trace[1416449836] 'process raft request' (duration: 43.423041ms)","trace[1416449836] 'compare' (duration: 151.610221ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:37:04.708436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.892716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:37:04.708489Z","caller":"traceutil/trace.go:171","msg":"trace[866481575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:352; }","duration":"181.972559ms","start":"2026-02-06T00:37:04.526497Z","end":"2026-02-06T00:37:04.70847Z","steps":["trace[866481575] 'agreement among raft nodes before linearized reading' (duration: 181.890186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:04.708148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.034425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4116"} {"level":"warn","ts":"2026-02-06T00:37:04.708876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.806505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-06T00:37:04.7089Z","caller":"traceutil/trace.go:171","msg":"trace[1963932158] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:352; }","duration":"147.896039ms","start":"2026-02-06T00:37:04.560995Z","end":"2026-02-06T00:37:04.708891Z","steps":["trace[1963932158] 'agreement among raft nodes before linearized reading' (duration: 147.857647ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:04.708914Z","caller":"traceutil/trace.go:171","msg":"trace[116157887] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:352; }","duration":"199.815896ms","start":"2026-02-06T00:37:04.509069Z","end":"2026-02-06T00:37:04.708885Z","steps":["trace[116157887] 'agreement among raft nodes before linearized reading' (duration: 198.966493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:04.709212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.37988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-06T00:37:04.709231Z","caller":"traceutil/trace.go:171","msg":"trace[625350746] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:352; }","duration":"198.406732ms","start":"2026-02-06T00:37:04.510818Z","end":"2026-02-06T00:37:04.709225Z","steps":["trace[625350746] 'agreement among raft nodes before linearized reading' (duration: 198.36735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:04.709358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.546777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4116"} {"level":"info","ts":"2026-02-06T00:37:04.709373Z","caller":"traceutil/trace.go:171","msg":"trace[1633967794] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:352; }","duration":"198.580068ms","start":"2026-02-06T00:37:04.510788Z","end":"2026-02-06T00:37:04.709368Z","steps":["trace[1633967794] 'agreement among raft nodes before linearized reading' (duration: 198.544487ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:04.921853Z","caller":"traceutil/trace.go:171","msg":"trace[1857167707] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"193.71957ms","start":"2026-02-06T00:37:04.728101Z","end":"2026-02-06T00:37:04.92182Z","steps":["trace[1857167707] 'process raft request' (duration: 192.91742ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:04.940156Z","caller":"traceutil/trace.go:171","msg":"trace[979328890] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"202.221048ms","start":"2026-02-06T00:37:04.737894Z","end":"2026-02-06T00:37:04.940115Z","steps":["trace[979328890] 'process raft request' (duration: 202.00513ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:04.940416Z","caller":"traceutil/trace.go:171","msg":"trace[1737477810] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:363; }","duration":"181.131167ms","start":"2026-02-06T00:37:04.759262Z","end":"2026-02-06T00:37:04.940394Z","steps":["trace[1737477810] 'read index received' (duration: 161.728841ms)","trace[1737477810] 'applied index is now lower than readState.Index' (duration: 19.401246ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:37:04.940647Z","caller":"traceutil/trace.go:171","msg":"trace[1016191286] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"202.501829ms","start":"2026-02-06T00:37:04.738128Z","end":"2026-02-06T00:37:04.94063Z","steps":["trace[1016191286] 'process raft request' (duration: 201.923627ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:04.940844Z","caller":"traceutil/trace.go:171","msg":"trace[2121522746] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"202.631854ms","start":"2026-02-06T00:37:04.738201Z","end":"2026-02-06T00:37:04.940833Z","steps":["trace[2121522746] 'process raft request' (duration: 201.898636ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:04.940977Z","caller":"traceutil/trace.go:171","msg":"trace[2087819329] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"201.390316ms","start":"2026-02-06T00:37:04.739577Z","end":"2026-02-06T00:37:04.940967Z","steps":["trace[2087819329] 'process raft request' (duration: 200.781253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:04.941158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.871875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-06T00:37:04.941188Z","caller":"traceutil/trace.go:171","msg":"trace[1440237419] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:361; }","duration":"181.983029ms","start":"2026-02-06T00:37:04.759197Z","end":"2026-02-06T00:37:04.941181Z","steps":["trace[1440237419] 'agreement among raft nodes before linearized reading' (duration: 181.895366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:04.941318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.809121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-06T00:37:04.941347Z","caller":"traceutil/trace.go:171","msg":"trace[338921393] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:361; }","duration":"130.869423ms","start":"2026-02-06T00:37:04.810471Z","end":"2026-02-06T00:37:04.94134Z","steps":["trace[338921393] 'agreement among raft nodes before linearized reading' (duration: 130.814631ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:04.941493Z","caller":"traceutil/trace.go:171","msg":"trace[652951273] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"203.215246ms","start":"2026-02-06T00:37:04.738261Z","end":"2026-02-06T00:37:04.941476Z","steps":["trace[652951273] 'process raft request' (duration: 201.880994ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:04.941751Z","caller":"traceutil/trace.go:171","msg":"trace[395752173] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"203.461356ms","start":"2026-02-06T00:37:04.73828Z","end":"2026-02-06T00:37:04.941742Z","steps":["trace[395752173] 'process raft request' (duration: 201.939017ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:04.942003Z","caller":"traceutil/trace.go:171","msg":"trace[858465618] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"203.668803ms","start":"2026-02-06T00:37:04.738324Z","end":"2026-02-06T00:37:04.941993Z","steps":["trace[858465618] 'process raft request' (duration: 201.945557ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:04.942106Z","caller":"traceutil/trace.go:171","msg":"trace[2099276970] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"203.725915ms","start":"2026-02-06T00:37:04.738371Z","end":"2026-02-06T00:37:04.942097Z","steps":["trace[2099276970] 'process raft request' (duration: 201.940657ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:05.186996Z","caller":"traceutil/trace.go:171","msg":"trace[86625448] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"177.96712ms","start":"2026-02-06T00:37:05.00896Z","end":"2026-02-06T00:37:05.186927Z","steps":["trace[86625448] 'process raft request' (duration: 122.285524ms)","trace[86625448] 'compare' (duration: 55.461018ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:37:05.259809Z","caller":"traceutil/trace.go:171","msg":"trace[711825842] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"250.507447ms","start":"2026-02-06T00:37:05.009271Z","end":"2026-02-06T00:37:05.259779Z","steps":["trace[711825842] 'process raft request' (duration: 249.915594ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:05.261035Z","caller":"traceutil/trace.go:171","msg":"trace[581643984] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:385; }","duration":"246.485842ms","start":"2026-02-06T00:37:05.014518Z","end":"2026-02-06T00:37:05.261004Z","steps":["trace[581643984] 'read index received' (duration: 116.749071ms)","trace[581643984] 'applied index is now lower than readState.Index' (duration: 129.735461ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:37:05.261589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.037363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-06T00:37:05.262355Z","caller":"traceutil/trace.go:171","msg":"trace[1970057076] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:380; }","duration":"247.871666ms","start":"2026-02-06T00:37:05.014467Z","end":"2026-02-06T00:37:05.262339Z","steps":["trace[1970057076] 'agreement among raft nodes before linearized reading' (duration: 246.971921ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:05.262876Z","caller":"traceutil/trace.go:171","msg":"trace[1910305556] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"245.38933ms","start":"2026-02-06T00:37:05.017474Z","end":"2026-02-06T00:37:05.262863Z","steps":["trace[1910305556] 'process raft request' (duration: 243.06103ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:05.261798Z","caller":"traceutil/trace.go:171","msg":"trace[417291494] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"247.522012ms","start":"2026-02-06T00:37:05.014262Z","end":"2026-02-06T00:37:05.261784Z","steps":["trace[417291494] 'process raft request' (duration: 245.164582ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:05.261897Z","caller":"traceutil/trace.go:171","msg":"trace[1958914911] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"247.275023ms","start":"2026-02-06T00:37:05.014604Z","end":"2026-02-06T00:37:05.261879Z","steps":["trace[1958914911] 'process raft request' (duration: 245.853718ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:05.273697Z","caller":"traceutil/trace.go:171","msg":"trace[392639613] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"257.415184ms","start":"2026-02-06T00:37:05.014494Z","end":"2026-02-06T00:37:05.271909Z","steps":["trace[392639613] 'process raft request' (duration: 245.064098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:05.669257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.867945ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:37:05.669659Z","caller":"traceutil/trace.go:171","msg":"trace[674522080] linearizableReadLoop","detail":"{readStateIndex:398; appliedIndex:395; }","duration":"296.259991ms","start":"2026-02-06T00:37:05.373373Z","end":"2026-02-06T00:37:05.669633Z","steps":["trace[674522080] 'read index received' (duration: 102.98599ms)","trace[674522080] 'applied index is now lower than readState.Index' (duration: 193.272441ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:37:05.669799Z","caller":"traceutil/trace.go:171","msg":"trace[1983826745] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"304.082792ms","start":"2026-02-06T00:37:05.365701Z","end":"2026-02-06T00:37:05.669784Z","steps":["trace[1983826745] 'process raft request' (duration: 110.582773ms)","trace[1983826745] 'compare' (duration: 192.646307ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:37:05.669867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:05.365674Z","time spent":"304.160225ms","remote":"127.0.0.1:45646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3505,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:37:05.670178Z","caller":"traceutil/trace.go:171","msg":"trace[427315414] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"304.078732ms","start":"2026-02-06T00:37:05.366087Z","end":"2026-02-06T00:37:05.670166Z","steps":["trace[427315414] 'process raft request' (duration: 303.310273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:05.670229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:05.366081Z","time spent":"304.120935ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:37:05.670514Z","caller":"traceutil/trace.go:171","msg":"trace[1250506339] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"299.213205ms","start":"2026-02-06T00:37:05.371289Z","end":"2026-02-06T00:37:05.670503Z","steps":["trace[1250506339] 'process raft request' (duration: 298.185116ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:05.670633Z","caller":"traceutil/trace.go:171","msg":"trace[649040149] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"297.159806ms","start":"2026-02-06T00:37:05.373464Z","end":"2026-02-06T00:37:05.670623Z","steps":["trace[649040149] 'process raft request' (duration: 296.083334ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:05.670753Z","caller":"traceutil/trace.go:171","msg":"trace[1320624297] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"297.187857ms","start":"2026-02-06T00:37:05.373555Z","end":"2026-02-06T00:37:05.670743Z","steps":["trace[1320624297] 'process raft request' (duration: 296.028862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:05.670908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.52064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-02-06T00:37:05.670938Z","caller":"traceutil/trace.go:171","msg":"trace[2091370195] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:389; }","duration":"297.588483ms","start":"2026-02-06T00:37:05.37334Z","end":"2026-02-06T00:37:05.670928Z","steps":["trace[2091370195] 'agreement among raft nodes before linearized reading' (duration: 297.50706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:05.6711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.375261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-06T00:37:05.671128Z","caller":"traceutil/trace.go:171","msg":"trace[481245140] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:389; }","duration":"223.450264ms","start":"2026-02-06T00:37:05.447668Z","end":"2026-02-06T00:37:05.671118Z","steps":["trace[481245140] 'agreement among raft nodes before linearized reading' (duration: 223.396472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:05.67281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.231232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-06T00:37:05.672896Z","caller":"traceutil/trace.go:171","msg":"trace[1650697829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:389; }","duration":"205.409029ms","start":"2026-02-06T00:37:05.467466Z","end":"2026-02-06T00:37:05.672875Z","steps":["trace[1650697829] 'agreement among raft nodes before linearized reading' (duration: 205.262333ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:09.997619Z","caller":"traceutil/trace.go:171","msg":"trace[1969373173] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"257.182348ms","start":"2026-02-06T00:37:09.740421Z","end":"2026-02-06T00:37:09.997603Z","steps":["trace[1969373173] 'read index received' (duration: 257.060894ms)","trace[1969373173] 'applied index is now lower than readState.Index' (duration: 120.424µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:37:09.997711Z","caller":"traceutil/trace.go:171","msg":"trace[2116065332] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"276.937626ms","start":"2026-02-06T00:37:09.720766Z","end":"2026-02-06T00:37:09.997704Z","steps":["trace[2116065332] 'process raft request' (duration: 276.735198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:09.99794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.512272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-06T00:37:09.997961Z","caller":"traceutil/trace.go:171","msg":"trace[448926796] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:413; }","duration":"257.567643ms","start":"2026-02-06T00:37:09.740389Z","end":"2026-02-06T00:37:09.997956Z","steps":["trace[448926796] 'agreement among raft nodes before linearized reading' (duration: 257.49184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:09.998033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.105994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-06T00:37:09.998052Z","caller":"traceutil/trace.go:171","msg":"trace[1461626484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:413; }","duration":"241.204138ms","start":"2026-02-06T00:37:09.756843Z","end":"2026-02-06T00:37:09.998048Z","steps":["trace[1461626484] 'agreement among raft nodes before linearized reading' (duration: 241.177607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:09.998141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.270629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-06T00:37:09.998158Z","caller":"traceutil/trace.go:171","msg":"trace[1164712491] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:413; }","duration":"170.311511ms","start":"2026-02-06T00:37:09.827842Z","end":"2026-02-06T00:37:09.998154Z","steps":["trace[1164712491] 'agreement among raft nodes before linearized reading' (duration: 170.28001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:09.999139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.080072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T00:37:09.999172Z","caller":"traceutil/trace.go:171","msg":"trace[895928433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:413; }","duration":"242.124123ms","start":"2026-02-06T00:37:09.757042Z","end":"2026-02-06T00:37:09.999166Z","steps":["trace[895928433] 'agreement among raft nodes before linearized reading' (duration: 242.070001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:11.541467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008395488s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:37:11.54278Z","caller":"traceutil/trace.go:171","msg":"trace[585764784] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"1.522829141s","start":"2026-02-06T00:37:10.019922Z","end":"2026-02-06T00:37:11.542751Z","steps":["trace[585764784] 'process raft request' (duration: 1.521869844s)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:11.543036Z","caller":"traceutil/trace.go:171","msg":"trace[1618900129] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"1.288907397s","start":"2026-02-06T00:37:10.25411Z","end":"2026-02-06T00:37:11.543018Z","steps":["trace[1618900129] 'process raft request' (duration: 1.28771955s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:11.543412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:10.254078Z","time spent":"1.289115326s","remote":"127.0.0.1:45630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4098,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:37:11.543644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:10.019917Z","time spent":"1.522930355s","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:37:11.543756Z","caller":"traceutil/trace.go:171","msg":"trace[921098869] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"1.168289378s","start":"2026-02-06T00:37:10.375399Z","end":"2026-02-06T00:37:11.543688Z","steps":["trace[921098869] 'process raft request' (duration: 1.166478017s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:11.543909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:10.375377Z","time spent":"1.168456405s","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:37:11.544236Z","caller":"traceutil/trace.go:171","msg":"trace[412849239] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"1.530082294s","start":"2026-02-06T00:37:10.014135Z","end":"2026-02-06T00:37:11.544217Z","steps":["trace[412849239] 'process raft request' (duration: 518.849525ms)","trace[412849239] 'compare' (duration: 1.00818228s)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:37:11.544603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:10.014119Z","time spent":"1.530163937s","remote":"127.0.0.1:45646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6883,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:37:11.547511Z","caller":"traceutil/trace.go:171","msg":"trace[584210502] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"1.527799286s","start":"2026-02-06T00:37:10.019697Z","end":"2026-02-06T00:37:11.547496Z","steps":["trace[584210502] 'process raft request' (duration: 1.521949347s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:11.548159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:10.019684Z","time spent":"1.527870568s","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:37:11.548582Z","caller":"traceutil/trace.go:171","msg":"trace[561522611] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"1.528714591s","start":"2026-02-06T00:37:10.01985Z","end":"2026-02-06T00:37:11.548564Z","steps":["trace[561522611] 'process raft request' (duration: 1.521898585s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:11.548944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:10.019844Z","time spent":"1.528778694s","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:37:12.289297Z","caller":"traceutil/trace.go:171","msg":"trace[311089244] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:432; }","duration":"272.008087ms","start":"2026-02-06T00:37:12.017259Z","end":"2026-02-06T00:37:12.289267Z","steps":["trace[311089244] 'read index received' (duration: 270.454856ms)","trace[311089244] 'applied index is now lower than readState.Index' (duration: 1.551891ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:37:12.289478Z","caller":"traceutil/trace.go:171","msg":"trace[165065454] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"725.993139ms","start":"2026-02-06T00:37:11.563457Z","end":"2026-02-06T00:37:12.28945Z","steps":["trace[165065454] 'process raft request' (duration: 724.341775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:12.289515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.194365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-06T00:37:12.289557Z","caller":"traceutil/trace.go:171","msg":"trace[1061374077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:420; }","duration":"272.373522ms","start":"2026-02-06T00:37:12.017168Z","end":"2026-02-06T00:37:12.289542Z","steps":["trace[1061374077] 'agreement among raft nodes before linearized reading' (duration: 272.209805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:12.2896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:11.563342Z","time spent":"726.191217ms","remote":"127.0.0.1:45646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4583,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:37:16.626781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.181584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:37:16.626843Z","caller":"traceutil/trace.go:171","msg":"trace[152780587] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:462; }","duration":"101.313079ms","start":"2026-02-06T00:37:16.525516Z","end":"2026-02-06T00:37:16.626829Z","steps":["trace[152780587] 'range keys from in-memory index tree' (duration: 101.059399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:28.966621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.29598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4107"} {"level":"info","ts":"2026-02-06T00:37:28.9667Z","caller":"traceutil/trace.go:171","msg":"trace[1609238364] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:524; }","duration":"335.410225ms","start":"2026-02-06T00:37:28.631272Z","end":"2026-02-06T00:37:28.966682Z","steps":["trace[1609238364] 'range keys from in-memory index tree' (duration: 335.226558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:28.966739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:28.631259Z","time spent":"335.471738ms","remote":"127.0.0.1:45630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4131,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-06T00:37:31.841498Z","caller":"traceutil/trace.go:171","msg":"trace[430019030] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"179.460899ms","start":"2026-02-06T00:37:31.662019Z","end":"2026-02-06T00:37:31.84148Z","steps":["trace[430019030] 'read index received' (duration: 179.215089ms)","trace[430019030] 'applied index is now lower than readState.Index' (duration: 244.83µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:37:31.841806Z","caller":"traceutil/trace.go:171","msg":"trace[235145778] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"186.223061ms","start":"2026-02-06T00:37:31.655549Z","end":"2026-02-06T00:37:31.841772Z","steps":["trace[235145778] 'process raft request' (duration: 185.722111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:31.842119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.065023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-06T00:37:31.842154Z","caller":"traceutil/trace.go:171","msg":"trace[683869666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:530; }","duration":"180.140827ms","start":"2026-02-06T00:37:31.662002Z","end":"2026-02-06T00:37:31.842143Z","steps":["trace[683869666] 'agreement among raft nodes before linearized reading' (duration: 179.98193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:32.305227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.598709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:37:32.305566Z","caller":"traceutil/trace.go:171","msg":"trace[691680023] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"457.825798ms","start":"2026-02-06T00:37:31.847719Z","end":"2026-02-06T00:37:32.305545Z","steps":["trace[691680023] 'process raft request' (duration: 457.680162ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:32.305586Z","caller":"traceutil/trace.go:171","msg":"trace[1626575311] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"540.595868ms","start":"2026-02-06T00:37:31.764905Z","end":"2026-02-06T00:37:32.305501Z","steps":["trace[1626575311] 'process raft request' (duration: 161.206457ms)","trace[1626575311] 'compare' (duration: 377.746905ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:37:32.306884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:31.764883Z","time spent":"541.936432ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:37:32.307268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.244975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:37:32.307433Z","caller":"traceutil/trace.go:171","msg":"trace[219767970] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:532; }","duration":"634.040466ms","start":"2026-02-06T00:37:31.673371Z","end":"2026-02-06T00:37:32.307412Z","steps":["trace[219767970] 'agreement among raft nodes before linearized reading' (duration: 633.245725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:32.307671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:31.673358Z","time spent":"634.238814ms","remote":"127.0.0.1:45540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-02-06T00:37:32.305804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:31.847688Z","time spent":"457.945232ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:37:32.306451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.092245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T00:37:32.310006Z","caller":"traceutil/trace.go:171","msg":"trace[1021715275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:532; }","duration":"610.836256ms","start":"2026-02-06T00:37:31.69915Z","end":"2026-02-06T00:37:32.309986Z","steps":["trace[1021715275] 'agreement among raft nodes before linearized reading' (duration: 607.047404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:32.310105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:31.699139Z","time spent":"610.94473ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-06T00:37:32.305925Z","caller":"traceutil/trace.go:171","msg":"trace[497794563] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"464.269876ms","start":"2026-02-06T00:37:31.841576Z","end":"2026-02-06T00:37:32.305846Z","steps":["trace[497794563] 'read index received' (duration: 84.545922ms)","trace[497794563] 'applied index is now lower than readState.Index' (duration: 379.722764ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:37:32.771326Z","caller":"traceutil/trace.go:171","msg":"trace[78096430] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"313.675946ms","start":"2026-02-06T00:37:32.457618Z","end":"2026-02-06T00:37:32.771294Z","steps":["trace[78096430] 'read index received' (duration: 310.625514ms)","trace[78096430] 'applied index is now lower than readState.Index' (duration: 3.049222ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:37:32.771642Z","caller":"traceutil/trace.go:171","msg":"trace[1144009347] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"454.435598ms","start":"2026-02-06T00:37:32.317188Z","end":"2026-02-06T00:37:32.771624Z","steps":["trace[1144009347] 'process raft request' (duration: 451.143476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:32.77178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:32.317145Z","time spent":"454.535983ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:37:32.772431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.814141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-06T00:37:32.772479Z","caller":"traceutil/trace.go:171","msg":"trace[1762155225] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:533; }","duration":"314.912375ms","start":"2026-02-06T00:37:32.457554Z","end":"2026-02-06T00:37:32.772466Z","steps":["trace[1762155225] 'agreement among raft nodes before linearized reading' (duration: 314.427576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:32.772518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:32.457526Z","time spent":"314.985709ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T00:37:32.772697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.158579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:37:32.772813Z","caller":"traceutil/trace.go:171","msg":"trace[1861215114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"211.289884ms","start":"2026-02-06T00:37:32.561499Z","end":"2026-02-06T00:37:32.772789Z","steps":["trace[1861215114] 'agreement among raft nodes before linearized reading' (duration: 211.17374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:32.773658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.459853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:37:32.773718Z","caller":"traceutil/trace.go:171","msg":"trace[1868021448] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:533; }","duration":"154.521135ms","start":"2026-02-06T00:37:32.619184Z","end":"2026-02-06T00:37:32.773705Z","steps":["trace[1868021448] 'agreement among raft nodes before linearized reading' (duration: 154.197532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:37.295564Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056226146005583576,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T00:37:37.6106Z","caller":"traceutil/trace.go:171","msg":"trace[874364707] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"816.021573ms","start":"2026-02-06T00:37:36.794552Z","end":"2026-02-06T00:37:37.610573Z","steps":["trace[874364707] 'read index received' (duration: 815.866833ms)","trace[874364707] 'applied index is now lower than readState.Index' (duration: 154.09µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:37:37.610687Z","caller":"traceutil/trace.go:171","msg":"trace[1047236301] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"817.592003ms","start":"2026-02-06T00:37:36.79309Z","end":"2026-02-06T00:37:37.610682Z","steps":["trace[1047236301] 'process raft request' (duration: 817.379603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:37.61077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:36.79308Z","time spent":"817.624563ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:37:37.610931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.61095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qbmjh\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-06T00:37:37.611005Z","caller":"traceutil/trace.go:171","msg":"trace[149367862] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qbmjh; range_end:; response_count:1; response_revision:567; }","duration":"367.72995ms","start":"2026-02-06T00:37:37.243257Z","end":"2026-02-06T00:37:37.610987Z","steps":["trace[149367862] 'agreement among raft nodes before linearized reading' (duration: 367.54311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:37.611044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:37.243236Z","time spent":"367.79952ms","remote":"127.0.0.1:45646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16680,"request content":"key:\"/registry/pods/kube-system/cilium-qbmjh\" "} {"level":"warn","ts":"2026-02-06T00:37:37.611107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.552553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-06T00:37:37.611139Z","caller":"traceutil/trace.go:171","msg":"trace[1653417657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:567; }","duration":"816.599223ms","start":"2026-02-06T00:37:36.794534Z","end":"2026-02-06T00:37:37.611133Z","steps":["trace[1653417657] 'agreement among raft nodes before linearized reading' (duration: 816.497883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:37.611156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:36.794526Z","time spent":"816.627163ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T00:37:38.366592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056226146005583580,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T00:37:38.437589Z","caller":"traceutil/trace.go:171","msg":"trace[592372714] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"816.87982ms","start":"2026-02-06T00:37:37.620688Z","end":"2026-02-06T00:37:38.437568Z","steps":["trace[592372714] 'process raft request' (duration: 816.79897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:38.437685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:37.620677Z","time spent":"816.95669ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:37:39.121857Z","caller":"traceutil/trace.go:171","msg":"trace[106399677] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:588; }","duration":"1.255648894s","start":"2026-02-06T00:37:37.866186Z","end":"2026-02-06T00:37:39.121835Z","steps":["trace[106399677] 'read index received' (duration: 571.371927ms)","trace[106399677] 'applied index is now lower than readState.Index' (duration: 684.275977ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:37:39.12214Z","caller":"traceutil/trace.go:171","msg":"trace[2042501687] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"1.492797276s","start":"2026-02-06T00:37:37.629331Z","end":"2026-02-06T00:37:39.122128Z","steps":["trace[2042501687] 'process raft request' (duration: 1.492365945s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:39.12224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:37.629315Z","time spent":"1.492861116s","remote":"127.0.0.1:45646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:37:39.122475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256293925s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-06T00:37:39.122513Z","caller":"traceutil/trace.go:171","msg":"trace[1053575716] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:569; }","duration":"1.256360295s","start":"2026-02-06T00:37:37.866143Z","end":"2026-02-06T00:37:39.122503Z","steps":["trace[1053575716] 'agreement among raft nodes before linearized reading' (duration: 1.256247945s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:39.122548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:37.866129Z","time spent":"1.256413155s","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-06T00:37:39.122745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.362711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-06T00:37:39.122781Z","caller":"traceutil/trace.go:171","msg":"trace[695885620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:569; }","duration":"791.444871ms","start":"2026-02-06T00:37:38.331327Z","end":"2026-02-06T00:37:39.122772Z","steps":["trace[695885620] 'agreement among raft nodes before linearized reading' (duration: 791.34819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:39.12283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:38.331313Z","time spent":"791.510921ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T00:37:39.123081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.618541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.206\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-06T00:37:39.123121Z","caller":"traceutil/trace.go:171","msg":"trace[513225602] range","detail":"{range_begin:/registry/masterleases/162.253.55.206; range_end:; response_count:1; response_revision:569; }","duration":"791.666531ms","start":"2026-02-06T00:37:38.331445Z","end":"2026-02-06T00:37:39.123112Z","steps":["trace[513225602] 'agreement among raft nodes before linearized reading' (duration: 791.578421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:39.123153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:38.331441Z","time spent":"791.705921ms","remote":"127.0.0.1:43316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.206\" "} {"level":"info","ts":"2026-02-06T00:37:39.253496Z","caller":"traceutil/trace.go:171","msg":"trace[411232743] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"104.189108ms","start":"2026-02-06T00:37:39.149293Z","end":"2026-02-06T00:37:39.253482Z","steps":["trace[411232743] 'process raft request' (duration: 104.118258ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:39.294398Z","caller":"traceutil/trace.go:171","msg":"trace[613872259] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"134.729022ms","start":"2026-02-06T00:37:39.159648Z","end":"2026-02-06T00:37:39.294377Z","steps":["trace[613872259] 'process raft request' (duration: 134.602282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:40.024474Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056226146005583584,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T00:37:40.525531Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056226146005583584,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T00:37:40.666629Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.372428421s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-06T00:37:41.524762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000601361s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-06T00:37:41.52484Z","caller":"traceutil/trace.go:171","msg":"trace[1353701667] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000716371s","start":"2026-02-06T00:37:39.52411Z","end":"2026-02-06T00:37:41.524826Z","steps":["trace[1353701667] 'agreement among raft nodes before linearized reading' (duration: 2.000619541s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:41.524877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:39.524098Z","time spent":"2.000771371s","remote":"127.0.0.1:43278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:37:41.812373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145493692s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:37:41.812437Z","caller":"traceutil/trace.go:171","msg":"trace[1903139873] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"2.288291525s","start":"2026-02-06T00:37:39.524136Z","end":"2026-02-06T00:37:41.812427Z","steps":["trace[1903139873] 'read index received' (duration: 1.142615213s)","trace[1903139873] 'applied index is now lower than readState.Index' (duration: 1.145675272s)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:37:41.812483Z","caller":"traceutil/trace.go:171","msg":"trace[1086585093] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"2.557789199s","start":"2026-02-06T00:37:39.254688Z","end":"2026-02-06T00:37:41.812478Z","steps":["trace[1086585093] 'process raft request' (duration: 1.412123457s)","trace[1086585093] 'compare' (duration: 1.145360841s)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:37:41.812511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:39.254677Z","time spent":"2.557818059s","remote":"127.0.0.1:43316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:37:41.812686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.18637956s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T00:37:41.812701Z","caller":"traceutil/trace.go:171","msg":"trace[1351154061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:572; }","duration":"2.186416181s","start":"2026-02-06T00:37:39.626279Z","end":"2026-02-06T00:37:41.812695Z","steps":["trace[1351154061] 'agreement among raft nodes before linearized reading' (duration: 2.18632697s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:41.812714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:39.626266Z","time spent":"2.186445581s","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T00:37:41.812801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.870624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:37:41.812815Z","caller":"traceutil/trace.go:171","msg":"trace[1565240736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"287.909675ms","start":"2026-02-06T00:37:41.524901Z","end":"2026-02-06T00:37:41.81281Z","steps":["trace[1565240736] 'agreement among raft nodes before linearized reading' (duration: 287.886475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:41.812891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.493437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-06T00:37:41.812904Z","caller":"traceutil/trace.go:171","msg":"trace[591917342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:572; }","duration":"515.531647ms","start":"2026-02-06T00:37:41.297369Z","end":"2026-02-06T00:37:41.812901Z","steps":["trace[591917342] 'agreement among raft nodes before linearized reading' (duration: 515.494967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:41.812915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:41.297354Z","time spent":"515.558338ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T00:37:41.812986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.848671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-06T00:37:41.812999Z","caller":"traceutil/trace.go:171","msg":"trace[1080986871] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:572; }","duration":"552.894531ms","start":"2026-02-06T00:37:41.260099Z","end":"2026-02-06T00:37:41.812994Z","steps":["trace[1080986871] 'agreement among raft nodes before linearized reading' (duration: 552.861981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:41.813009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:41.260081Z","time spent":"552.925951ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-06T00:37:41.813076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.372739381s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-06T00:37:41.813086Z","caller":"traceutil/trace.go:171","msg":"trace[2110337439] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:572; }","duration":"1.372774071s","start":"2026-02-06T00:37:40.440309Z","end":"2026-02-06T00:37:41.813083Z","steps":["trace[2110337439] 'agreement among raft nodes before linearized reading' (duration: 1.372742151s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:41.813097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:40.44029Z","time spent":"1.372804471s","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T00:37:44.027276Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056226146005583612,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T00:37:44.038087Z","caller":"traceutil/trace.go:171","msg":"trace[1593840106] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"511.111645ms","start":"2026-02-06T00:37:43.526938Z","end":"2026-02-06T00:37:44.038049Z","steps":["trace[1593840106] 'read index received' (duration: 502.296049ms)","trace[1593840106] 'applied index is now lower than readState.Index' (duration: 8.813766ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:37:44.038194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.237006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:37:44.038236Z","caller":"traceutil/trace.go:171","msg":"trace[1837390791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"511.325166ms","start":"2026-02-06T00:37:43.526899Z","end":"2026-02-06T00:37:44.038224Z","steps":["trace[1837390791] 'agreement among raft nodes before linearized reading' (duration: 511.230476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:44.038262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:37:43.526878Z","time spent":"511.379567ms","remote":"127.0.0.1:43284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:37:44.038315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.192462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-06T00:37:44.038363Z","caller":"traceutil/trace.go:171","msg":"trace[1033026822] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"163.257923ms","start":"2026-02-06T00:37:43.875096Z","end":"2026-02-06T00:37:44.038354Z","steps":["trace[1033026822] 'agreement among raft nodes before linearized reading' (duration: 163.097962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:44.038533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.30138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T00:37:44.038549Z","caller":"traceutil/trace.go:171","msg":"trace[911830923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"154.33577ms","start":"2026-02-06T00:37:43.884208Z","end":"2026-02-06T00:37:44.038544Z","steps":["trace[911830923] 'agreement among raft nodes before linearized reading' (duration: 154.25049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:44.038746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.915232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-06T00:37:44.038761Z","caller":"traceutil/trace.go:171","msg":"trace[1905733512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"154.949283ms","start":"2026-02-06T00:37:43.883808Z","end":"2026-02-06T00:37:44.038757Z","steps":["trace[1905733512] 'agreement among raft nodes before linearized reading' (duration: 154.902903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:44.038889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.553515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-06T00:37:44.038904Z","caller":"traceutil/trace.go:171","msg":"trace[1867018775] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"155.589885ms","start":"2026-02-06T00:37:43.88331Z","end":"2026-02-06T00:37:44.0389Z","steps":["trace[1867018775] 'agreement among raft nodes before linearized reading' (duration: 155.549195ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:44.59978Z","caller":"traceutil/trace.go:171","msg":"trace[1248901115] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"135.146533ms","start":"2026-02-06T00:37:44.464584Z","end":"2026-02-06T00:37:44.599731Z","steps":["trace[1248901115] 'process raft request' (duration: 125.734814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:37:46.762163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.028904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:37:46.762726Z","caller":"traceutil/trace.go:171","msg":"trace[437998646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"189.596437ms","start":"2026-02-06T00:37:46.573078Z","end":"2026-02-06T00:37:46.762674Z","steps":["trace[437998646] 'range keys from in-memory index tree' (duration: 188.928244ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:46.767877Z","caller":"traceutil/trace.go:171","msg":"trace[1633520468] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"148.963869ms","start":"2026-02-06T00:37:46.618899Z","end":"2026-02-06T00:37:46.767863Z","steps":["trace[1633520468] 'process raft request' (duration: 148.813979ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:52.303764Z","caller":"traceutil/trace.go:171","msg":"trace[1110650866] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:721; }","duration":"201.555672ms","start":"2026-02-06T00:37:52.102155Z","end":"2026-02-06T00:37:52.303711Z","steps":["trace[1110650866] 'read index received' (duration: 14.169673ms)","trace[1110650866] 'applied index is now lower than readState.Index' (duration: 187.385049ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:37:52.303993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.812544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-06T00:37:52.304026Z","caller":"traceutil/trace.go:171","msg":"trace[852652465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:699; }","duration":"201.874394ms","start":"2026-02-06T00:37:52.102142Z","end":"2026-02-06T00:37:52.304016Z","steps":["trace[852652465] 'agreement among raft nodes before linearized reading' (duration: 201.698023ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:37:52.304353Z","caller":"traceutil/trace.go:171","msg":"trace[816612640] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"203.688379ms","start":"2026-02-06T00:37:52.100652Z","end":"2026-02-06T00:37:52.304341Z","steps":["trace[816612640] 'process raft request' (duration: 180.699426ms)","trace[816612640] 'compare' (duration: 22.257577ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:37:55.648422Z","caller":"traceutil/trace.go:171","msg":"trace[1058822707] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"183.648092ms","start":"2026-02-06T00:37:55.464543Z","end":"2026-02-06T00:37:55.648191Z","steps":["trace[1058822707] 'process raft request' (duration: 153.486963ms)","trace[1058822707] 'compare' (duration: 30.078218ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:37:55.654302Z","caller":"traceutil/trace.go:171","msg":"trace[1140775648] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"179.997439ms","start":"2026-02-06T00:37:55.474267Z","end":"2026-02-06T00:37:55.654265Z","steps":["trace[1140775648] 'process raft request' (duration: 178.943589ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:38:02.752823Z","caller":"traceutil/trace.go:171","msg":"trace[1471573837] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"139.047557ms","start":"2026-02-06T00:38:02.613761Z","end":"2026-02-06T00:38:02.752809Z","steps":["trace[1471573837] 'process raft request' (duration: 138.714603ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:38:24.462625Z","caller":"traceutil/trace.go:171","msg":"trace[732573677] linearizableReadLoop","detail":"{readStateIndex:881; appliedIndex:880; }","duration":"120.114219ms","start":"2026-02-06T00:38:24.342495Z","end":"2026-02-06T00:38:24.46261Z","steps":["trace[732573677] 'read index received' (duration: 119.948646ms)","trace[732573677] 'applied index is now lower than readState.Index' (duration: 165.053µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:38:24.462754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.257651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-06T00:38:24.462771Z","caller":"traceutil/trace.go:171","msg":"trace[216311202] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:848; }","duration":"120.324763ms","start":"2026-02-06T00:38:24.34244Z","end":"2026-02-06T00:38:24.462765Z","steps":["trace[216311202] 'agreement among raft nodes before linearized reading' (duration: 120.234121ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:38:24.462835Z","caller":"traceutil/trace.go:171","msg":"trace[2129390605] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"136.300042ms","start":"2026-02-06T00:38:24.326514Z","end":"2026-02-06T00:38:24.462814Z","steps":["trace[2129390605] 'process raft request' (duration: 135.970886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:38:25.293379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.929975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:38:25.293425Z","caller":"traceutil/trace.go:171","msg":"trace[99191084] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:852; }","duration":"103.009657ms","start":"2026-02-06T00:38:25.190405Z","end":"2026-02-06T00:38:25.293414Z","steps":["trace[99191084] 'count revisions from in-memory index tree' (duration: 102.878444ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:38:26.646869Z","caller":"traceutil/trace.go:171","msg":"trace[221671309] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"100.108916ms","start":"2026-02-06T00:38:26.546743Z","end":"2026-02-06T00:38:26.646852Z","steps":["trace[221671309] 'process raft request' (duration: 55.46165ms)","trace[221671309] 'compare' (duration: 44.403561ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:38:36.937408Z","caller":"traceutil/trace.go:171","msg":"trace[1020297458] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"115.933945ms","start":"2026-02-06T00:38:36.821447Z","end":"2026-02-06T00:38:36.937381Z","steps":["trace[1020297458] 'process raft request' (duration: 79.554564ms)","trace[1020297458] 'compare' (duration: 36.266579ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:38:44.852136Z","caller":"traceutil/trace.go:171","msg":"trace[776488215] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"110.49584ms","start":"2026-02-06T00:38:44.741625Z","end":"2026-02-06T00:38:44.852121Z","steps":["trace[776488215] 'process raft request' (duration: 110.364857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:38:45.179814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.470947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-06T00:38:45.179882Z","caller":"traceutil/trace.go:171","msg":"trace[1589143956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:905; }","duration":"130.573649ms","start":"2026-02-06T00:38:45.049293Z","end":"2026-02-06T00:38:45.179867Z","steps":["trace[1589143956] 'range keys from in-memory index tree' (duration: 130.332373ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:38:45.446305Z","caller":"traceutil/trace.go:171","msg":"trace[671535192] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:943; }","duration":"177.558145ms","start":"2026-02-06T00:38:45.268722Z","end":"2026-02-06T00:38:45.44628Z","steps":["trace[671535192] 'read index received' (duration: 152.495118ms)","trace[671535192] 'applied index is now lower than readState.Index' (duration: 25.062047ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:38:45.446549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.809781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:38:45.446596Z","caller":"traceutil/trace.go:171","msg":"trace[1865032120] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:907; }","duration":"177.894843ms","start":"2026-02-06T00:38:45.26869Z","end":"2026-02-06T00:38:45.446585Z","steps":["trace[1865032120] 'agreement among raft nodes before linearized reading' (duration: 177.77644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:38:45.44689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.730666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-06T00:38:45.446954Z","caller":"traceutil/trace.go:171","msg":"trace[859240237] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:907; }","duration":"126.945651ms","start":"2026-02-06T00:38:45.319993Z","end":"2026-02-06T00:38:45.446938Z","steps":["trace[859240237] 'agreement among raft nodes before linearized reading' (duration: 126.674844ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:38:45.447052Z","caller":"traceutil/trace.go:171","msg":"trace[60067965] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"260.820309ms","start":"2026-02-06T00:38:45.186221Z","end":"2026-02-06T00:38:45.447041Z","steps":["trace[60067965] 'process raft request' (duration: 235.038315ms)","trace[60067965] 'compare' (duration: 24.896093ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:39:09.597025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056226146005584637,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T00:39:09.623499Z","caller":"traceutil/trace.go:171","msg":"trace[77432641] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1052; }","duration":"526.701006ms","start":"2026-02-06T00:39:09.096784Z","end":"2026-02-06T00:39:09.623485Z","steps":["trace[77432641] 'read index received' (duration: 526.599033ms)","trace[77432641] 'applied index is now lower than readState.Index' (duration: 101.303µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:39:09.623578Z","caller":"traceutil/trace.go:171","msg":"trace[945377605] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"652.775496ms","start":"2026-02-06T00:39:08.970795Z","end":"2026-02-06T00:39:09.62357Z","steps":["trace[945377605] 'process raft request' (duration: 652.593041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:39:09.623657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:39:08.970775Z","time spent":"652.819777ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:39:09.623802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.471389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T00:39:09.623856Z","caller":"traceutil/trace.go:171","msg":"trace[972860625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1010; }","duration":"251.550641ms","start":"2026-02-06T00:39:09.37229Z","end":"2026-02-06T00:39:09.623841Z","steps":["trace[972860625] 'agreement among raft nodes before linearized reading' (duration: 251.411967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:39:09.623985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.19996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-06T00:39:09.62401Z","caller":"traceutil/trace.go:171","msg":"trace[1075126185] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1010; }","duration":"527.257572ms","start":"2026-02-06T00:39:09.096746Z","end":"2026-02-06T00:39:09.624004Z","steps":["trace[1075126185] 'agreement among raft nodes before linearized reading' (duration: 527.2053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:39:09.624029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:39:09.096728Z","time spent":"527.295532ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-06T00:39:09.624106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.853465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:39:09.624131Z","caller":"traceutil/trace.go:171","msg":"trace[783687405] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1010; }","duration":"217.899755ms","start":"2026-02-06T00:39:09.406223Z","end":"2026-02-06T00:39:09.624123Z","steps":["trace[783687405] 'agreement among raft nodes before linearized reading' (duration: 217.864074ms)"],"step_count":1}