{"level":"warn","ts":"2026-03-19T17:46:31.987799Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T17:46:31.988742Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.177: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://199.19.213.177:2380","--initial-cluster=instance=https://199.19.213.177:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.177:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.177: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-03-19T17:46:31.988798Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T17:46:31.988805Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.177:2380"]} {"level":"info","ts":"2026-03-19T17:46:31.98882Z","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-03-19T17:46:31.990167Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.177:2379"]} {"level":"info","ts":"2026-03-19T17:46:31.99024Z","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":8,"max-cpu-available":8,"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://199.19.213.177:2380"],"listen-peer-urls":["https://199.19.213.177:2380"],"advertise-client-urls":["https://199.19.213.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.177: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-03-19T17:46:31.997357Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.930688ms"} {"level":"info","ts":"2026-03-19T17:46:32.009714Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f577bc5ae95d61e1","cluster-id":"735860b212d0f0b6"} {"level":"info","ts":"2026-03-19T17:46:32.00979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 switched to configuration voters=()"} {"level":"info","ts":"2026-03-19T17:46:32.009813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became follower at term 0"} {"level":"info","ts":"2026-03-19T17:46:32.009827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f577bc5ae95d61e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-19T17:46:32.009873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became follower at term 1"} {"level":"info","ts":"2026-03-19T17:46:32.009909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 switched to configuration voters=(17687813160169202145)"} {"level":"warn","ts":"2026-03-19T17:46:32.02034Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-19T17:46:32.023157Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-19T17:46:32.025657Z","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-03-19T17:46:32.028538Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f577bc5ae95d61e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-19T17:46:32.029594Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f577bc5ae95d61e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-19T17:46:32.029675Z","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-03-19T17:46:32.029733Z","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-03-19T17:46:32.029745Z","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-03-19T17:46:32.029966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 switched to configuration voters=(17687813160169202145)"} {"level":"info","ts":"2026-03-19T17:46:32.030132Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"735860b212d0f0b6","local-member-id":"f577bc5ae95d61e1","added-peer-id":"f577bc5ae95d61e1","added-peer-peer-urls":["https://199.19.213.177:2380"]} {"level":"info","ts":"2026-03-19T17:46:32.032015Z","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-03-19T17:46:32.032258Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f577bc5ae95d61e1","initial-advertise-peer-urls":["https://199.19.213.177:2380"],"listen-peer-urls":["https://199.19.213.177:2380"],"advertise-client-urls":["https://199.19.213.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-19T17:46:32.03232Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-19T17:46:32.033358Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.177:2380"} {"level":"info","ts":"2026-03-19T17:46:32.033449Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.177:2380"} {"level":"info","ts":"2026-03-19T17:46:33.010271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 is starting a new election at term 1"} {"level":"info","ts":"2026-03-19T17:46:33.010337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-19T17:46:33.010369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 received MsgPreVoteResp from f577bc5ae95d61e1 at term 1"} {"level":"info","ts":"2026-03-19T17:46:33.010388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became candidate at term 2"} {"level":"info","ts":"2026-03-19T17:46:33.010404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 received MsgVoteResp from f577bc5ae95d61e1 at term 2"} {"level":"info","ts":"2026-03-19T17:46:33.010417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became leader at term 2"} {"level":"info","ts":"2026-03-19T17:46:33.01043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f577bc5ae95d61e1 elected leader f577bc5ae95d61e1 at term 2"} {"level":"info","ts":"2026-03-19T17:46:33.011164Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f577bc5ae95d61e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.177:2379]}","request-path":"/0/members/f577bc5ae95d61e1/attributes","cluster-id":"735860b212d0f0b6","publish-timeout":"7s"} {"level":"info","ts":"2026-03-19T17:46:33.01131Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T17:46:33.011376Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T17:46:33.011417Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-19T17:46:33.011479Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-19T17:46:33.011553Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:46:33.012464Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"735860b212d0f0b6","local-member-id":"f577bc5ae95d61e1","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:46:33.012693Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:46:33.012883Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:46:33.014217Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-19T17:46:33.014437Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.177:2379"} {"level":"info","ts":"2026-03-19T17:46:34.548174Z","caller":"traceutil/trace.go:171","msg":"trace[1266985367] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"124.776159ms","start":"2026-03-19T17:46:34.423361Z","end":"2026-03-19T17:46:34.548137Z","steps":["trace[1266985367] 'process raft request' (duration: 124.711557ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:34.548193Z","caller":"traceutil/trace.go:171","msg":"trace[1857017079] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"127.166553ms","start":"2026-03-19T17:46:34.420997Z","end":"2026-03-19T17:46:34.548163Z","steps":["trace[1857017079] 'process raft request' (duration: 37.69467ms)","trace[1857017079] 'compare' (duration: 89.202973ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:34.786527Z","caller":"traceutil/trace.go:171","msg":"trace[1063883690] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"154.693687ms","start":"2026-03-19T17:46:34.631809Z","end":"2026-03-19T17:46:34.786503Z","steps":["trace[1063883690] 'process raft request' (duration: 154.632495ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:34.786598Z","caller":"traceutil/trace.go:171","msg":"trace[1207384174] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"187.493466ms","start":"2026-03-19T17:46:34.599027Z","end":"2026-03-19T17:46:34.786521Z","steps":["trace[1207384174] 'process raft request' (duration: 109.990872ms)","trace[1207384174] 'compare' (duration: 77.260715ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:34.99206Z","caller":"traceutil/trace.go:171","msg":"trace[1739987982] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"180.348585ms","start":"2026-03-19T17:46:34.811682Z","end":"2026-03-19T17:46:34.99203Z","steps":["trace[1739987982] 'process raft request' (duration: 127.814246ms)","trace[1739987982] 'compare' (duration: 52.340073ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:34.992379Z","caller":"traceutil/trace.go:171","msg":"trace[555825855] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"168.060825ms","start":"2026-03-19T17:46:34.824243Z","end":"2026-03-19T17:46:34.992304Z","steps":["trace[555825855] 'process raft request' (duration: 167.748104ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:35.140445Z","caller":"traceutil/trace.go:171","msg":"trace[2026609043] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"140.541207ms","start":"2026-03-19T17:46:34.99988Z","end":"2026-03-19T17:46:35.140421Z","steps":["trace[2026609043] 'process raft request' (duration: 126.694506ms)","trace[2026609043] 'compare' (duration: 13.649914ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:35.338381Z","caller":"traceutil/trace.go:171","msg":"trace[1082073369] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"103.900412ms","start":"2026-03-19T17:46:35.234461Z","end":"2026-03-19T17:46:35.338361Z","steps":["trace[1082073369] 'process raft request' (duration: 103.8362ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:35.338411Z","caller":"traceutil/trace.go:171","msg":"trace[1969676855] linearizableReadLoop","detail":"{readStateIndex:74; appliedIndex:72; }","duration":"110.641136ms","start":"2026-03-19T17:46:35.227737Z","end":"2026-03-19T17:46:35.338378Z","steps":["trace[1969676855] 'read index received' (duration: 2.56651ms)","trace[1969676855] 'applied index is now lower than readState.Index' (duration: 108.071366ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:35.338559Z","caller":"traceutil/trace.go:171","msg":"trace[749608189] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"114.850913ms","start":"2026-03-19T17:46:35.223665Z","end":"2026-03-19T17:46:35.338516Z","steps":["trace[749608189] 'process raft request' (duration: 70.300794ms)","trace[749608189] 'compare' (duration: 44.215656ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:35.338705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.908026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-19T17:46:35.338779Z","caller":"traceutil/trace.go:171","msg":"trace[1782966386] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:71; }","duration":"111.05852ms","start":"2026-03-19T17:46:35.227706Z","end":"2026-03-19T17:46:35.338765Z","steps":["trace[1782966386] 'agreement among raft nodes before linearized reading' (duration: 110.905924ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:35.338848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.869895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-19T17:46:35.338947Z","caller":"traceutil/trace.go:171","msg":"trace[794850979] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:71; }","duration":"110.977948ms","start":"2026-03-19T17:46:35.227952Z","end":"2026-03-19T17:46:35.33893Z","steps":["trace[794850979] 'agreement among raft nodes before linearized reading' (duration: 110.783112ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:35.82637Z","caller":"traceutil/trace.go:171","msg":"trace[1540711475] transaction","detail":"{read_only:false; response_revision:98; number_of_response:1; }","duration":"119.300366ms","start":"2026-03-19T17:46:35.707045Z","end":"2026-03-19T17:46:35.826345Z","steps":["trace[1540711475] 'process raft request' (duration: 71.502555ms)","trace[1540711475] 'compare' (duration: 47.692848ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:36.780589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.166487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:36.780915Z","caller":"traceutil/trace.go:171","msg":"trace[1148147208] transaction","detail":"{read_only:false; response_revision:153; number_of_response:1; }","duration":"177.01901ms","start":"2026-03-19T17:46:36.603832Z","end":"2026-03-19T17:46:36.780851Z","steps":["trace[1148147208] 'process raft request' (duration: 63.047756ms)","trace[1148147208] 'compare' (duration: 113.032173ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:36.960158Z","caller":"traceutil/trace.go:171","msg":"trace[942527910] linearizableReadLoop","detail":"{readStateIndex:159; appliedIndex:157; }","duration":"176.611216ms","start":"2026-03-19T17:46:36.783525Z","end":"2026-03-19T17:46:36.960137Z","steps":["trace[942527910] 'read index received' (duration: 147.357326ms)","trace[942527910] 'applied index is now lower than readState.Index' (duration: 29.25304ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:36.960231Z","caller":"traceutil/trace.go:171","msg":"trace[1930046061] transaction","detail":"{read_only:false; response_revision:155; number_of_response:1; }","duration":"177.084603ms","start":"2026-03-19T17:46:36.783118Z","end":"2026-03-19T17:46:36.960203Z","steps":["trace[1930046061] 'process raft request' (duration: 176.976119ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:36.960283Z","caller":"traceutil/trace.go:171","msg":"trace[89353755] transaction","detail":"{read_only:false; response_revision:154; number_of_response:1; }","duration":"177.114354ms","start":"2026-03-19T17:46:36.783113Z","end":"2026-03-19T17:46:36.960227Z","steps":["trace[89353755] 'process raft request' (duration: 147.816252ms)","trace[89353755] 'compare' (duration: 29.026242ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:36.961043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.471526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:36.9611Z","caller":"traceutil/trace.go:171","msg":"trace[554496560] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller; range_end:; response_count:0; response_revision:155; }","duration":"177.59348ms","start":"2026-03-19T17:46:36.783488Z","end":"2026-03-19T17:46:36.961081Z","steps":["trace[554496560] 'agreement among raft nodes before linearized reading' (duration: 176.767821ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:37.186435Z","caller":"traceutil/trace.go:171","msg":"trace[212566536] transaction","detail":"{read_only:false; response_revision:159; number_of_response:1; }","duration":"154.473744ms","start":"2026-03-19T17:46:37.031938Z","end":"2026-03-19T17:46:37.186411Z","steps":["trace[212566536] 'process raft request' (duration: 122.712235ms)","trace[212566536] 'compare' (duration: 31.654535ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:37.668898Z","caller":"traceutil/trace.go:171","msg":"trace[1208316438] transaction","detail":"{read_only:false; response_revision:176; number_of_response:1; }","duration":"147.860208ms","start":"2026-03-19T17:46:37.521006Z","end":"2026-03-19T17:46:37.668866Z","steps":["trace[1208316438] 'process raft request' (duration: 106.338754ms)","trace[1208316438] 'compare' (duration: 41.365118ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:37.915462Z","caller":"traceutil/trace.go:171","msg":"trace[1849400375] transaction","detail":"{read_only:false; response_revision:181; number_of_response:1; }","duration":"199.889272ms","start":"2026-03-19T17:46:37.715548Z","end":"2026-03-19T17:46:37.915437Z","steps":["trace[1849400375] 'process raft request' (duration: 100.377761ms)","trace[1849400375] 'compare' (duration: 99.366266ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:38.209818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.448743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:38.209939Z","caller":"traceutil/trace.go:171","msg":"trace[155326842] transaction","detail":"{read_only:false; response_revision:185; number_of_response:1; }","duration":"204.065195ms","start":"2026-03-19T17:46:38.00585Z","end":"2026-03-19T17:46:38.209915Z","steps":["trace[155326842] 'process raft request' (duration: 35.438836ms)","trace[155326842] 'compare' (duration: 168.303688ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:38.67541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.663652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:38.675807Z","caller":"traceutil/trace.go:171","msg":"trace[1355895627] transaction","detail":"{read_only:false; response_revision:192; number_of_response:1; }","duration":"238.268408ms","start":"2026-03-19T17:46:38.437502Z","end":"2026-03-19T17:46:38.67577Z","steps":["trace[1355895627] 'process raft request' (duration: 86.175303ms)","trace[1355895627] 'compare' (duration: 151.246848ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:38.676033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.132822ms","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-03-19T17:46:38.676133Z","caller":"traceutil/trace.go:171","msg":"trace[873235397] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:192; }","duration":"215.245516ms","start":"2026-03-19T17:46:38.46087Z","end":"2026-03-19T17:46:38.676115Z","steps":["trace[873235397] 'agreement among raft nodes before linearized reading' (duration: 215.024589ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:38.675858Z","caller":"traceutil/trace.go:171","msg":"trace[2094674742] linearizableReadLoop","detail":"{readStateIndex:196; appliedIndex:195; }","duration":"214.958346ms","start":"2026-03-19T17:46:38.460877Z","end":"2026-03-19T17:46:38.675835Z","steps":["trace[2094674742] 'read index received' (duration: 62.814638ms)","trace[2094674742] 'applied index is now lower than readState.Index' (duration: 152.099967ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:38.676755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.563665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:38.676827Z","caller":"traceutil/trace.go:171","msg":"trace[110538223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:192; }","duration":"215.654729ms","start":"2026-03-19T17:46:38.461158Z","end":"2026-03-19T17:46:38.676813Z","steps":["trace[110538223] 'agreement among raft nodes before linearized reading' (duration: 215.508305ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:38.676957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.28394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:38.677043Z","caller":"traceutil/trace.go:171","msg":"trace[281871005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:192; }","duration":"139.513137ms","start":"2026-03-19T17:46:38.537508Z","end":"2026-03-19T17:46:38.677021Z","steps":["trace[281871005] 'agreement among raft nodes before linearized reading' (duration: 139.28406ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:38.895724Z","caller":"traceutil/trace.go:171","msg":"trace[171160762] transaction","detail":"{read_only:false; response_revision:195; number_of_response:1; }","duration":"143.795634ms","start":"2026-03-19T17:46:38.751906Z","end":"2026-03-19T17:46:38.895702Z","steps":["trace[171160762] 'process raft request' (duration: 79.714314ms)","trace[171160762] 'compare' (duration: 63.938535ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:38.895706Z","caller":"traceutil/trace.go:171","msg":"trace[2019374884] linearizableReadLoop","detail":"{readStateIndex:200; appliedIndex:199; }","duration":"101.780374ms","start":"2026-03-19T17:46:38.793898Z","end":"2026-03-19T17:46:38.895679Z","steps":["trace[2019374884] 'read index received' (duration: 37.675332ms)","trace[2019374884] 'applied index is now lower than readState.Index' (duration: 64.103652ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:38.89618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.264551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:38.896338Z","caller":"traceutil/trace.go:171","msg":"trace[523949751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:195; }","duration":"102.492868ms","start":"2026-03-19T17:46:38.793823Z","end":"2026-03-19T17:46:38.896316Z","steps":["trace[523949751] 'agreement among raft nodes before linearized reading' (duration: 101.942529ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:39.101904Z","caller":"traceutil/trace.go:171","msg":"trace[831849080] linearizableReadLoop","detail":"{readStateIndex:204; appliedIndex:203; }","duration":"104.762303ms","start":"2026-03-19T17:46:38.997119Z","end":"2026-03-19T17:46:39.101881Z","steps":["trace[831849080] 'read index received' (duration: 15.559868ms)","trace[831849080] 'applied index is now lower than readState.Index' (duration: 89.201465ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:39.101936Z","caller":"traceutil/trace.go:171","msg":"trace[514702947] transaction","detail":"{read_only:false; response_revision:199; number_of_response:1; }","duration":"108.746257ms","start":"2026-03-19T17:46:38.993156Z","end":"2026-03-19T17:46:39.101902Z","steps":["trace[514702947] 'process raft request' (duration: 19.588144ms)","trace[514702947] 'compare' (duration: 88.989698ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:39.102018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.893367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-public\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-19T17:46:39.102043Z","caller":"traceutil/trace.go:171","msg":"trace[617023336] range","detail":"{range_begin:/registry/namespaces/kube-public; range_end:; response_count:1; response_revision:199; }","duration":"104.970898ms","start":"2026-03-19T17:46:38.997065Z","end":"2026-03-19T17:46:39.102036Z","steps":["trace[617023336] 'agreement among raft nodes before linearized reading' (duration: 104.892727ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:39.657307Z","caller":"traceutil/trace.go:171","msg":"trace[281380746] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"480.064755ms","start":"2026-03-19T17:46:39.177225Z","end":"2026-03-19T17:46:39.65729Z","steps":["trace[281380746] 'process raft request' (duration: 479.868238ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:39.657276Z","caller":"traceutil/trace.go:171","msg":"trace[1395417876] linearizableReadLoop","detail":"{readStateIndex:206; appliedIndex:205; }","duration":"119.177868ms","start":"2026-03-19T17:46:39.538075Z","end":"2026-03-19T17:46:39.657253Z","steps":["trace[1395417876] 'read index received' (duration: 118.953121ms)","trace[1395417876] 'applied index is now lower than readState.Index' (duration: 223.917µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:39.65747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.386636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:39.657609Z","caller":"traceutil/trace.go:171","msg":"trace[1625713283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:201; }","duration":"119.53253ms","start":"2026-03-19T17:46:39.538016Z","end":"2026-03-19T17:46:39.657548Z","steps":["trace[1625713283] 'agreement among raft nodes before linearized reading' (duration: 119.350634ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:39.657708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:39.17719Z","time spent":"480.172399ms","remote":"127.0.0.1:51426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":726,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:46:39.842008Z","caller":"traceutil/trace.go:171","msg":"trace[1082868886] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"122.689466ms","start":"2026-03-19T17:46:39.719288Z","end":"2026-03-19T17:46:39.841978Z","steps":["trace[1082868886] 'process raft request' (duration: 62.369118ms)","trace[1082868886] 'compare' (duration: 60.170993ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:40.437497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.285897ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:40.437678Z","caller":"traceutil/trace.go:171","msg":"trace[936550002] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"551.450786ms","start":"2026-03-19T17:46:39.8862Z","end":"2026-03-19T17:46:40.437651Z","steps":["trace[936550002] 'process raft request' (duration: 71.935601ms)","trace[936550002] 'compare' (duration: 479.138663ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:40.437832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:39.886189Z","time spent":"551.5795ms","remote":"127.0.0.1:51426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":714,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T17:46:40.778998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.569447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:113"} {"level":"info","ts":"2026-03-19T17:46:40.779084Z","caller":"traceutil/trace.go:171","msg":"trace[1441287075] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:209; }","duration":"238.729012ms","start":"2026-03-19T17:46:40.540336Z","end":"2026-03-19T17:46:40.779065Z","steps":["trace[1441287075] 'range keys from in-memory index tree' (duration: 238.466213ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:40.779073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.535861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:40.779147Z","caller":"traceutil/trace.go:171","msg":"trace[1579344086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:209; }","duration":"186.632355ms","start":"2026-03-19T17:46:40.592496Z","end":"2026-03-19T17:46:40.779128Z","steps":["trace[1579344086] 'range keys from in-memory index tree' (duration: 186.457358ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:40.946556Z","caller":"traceutil/trace.go:171","msg":"trace[1676541404] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"165.795414ms","start":"2026-03-19T17:46:40.780703Z","end":"2026-03-19T17:46:40.946499Z","steps":["trace[1676541404] 'process raft request' (duration: 165.618498ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:40.946455Z","caller":"traceutil/trace.go:171","msg":"trace[1940476643] linearizableReadLoop","detail":"{readStateIndex:215; appliedIndex:214; }","duration":"163.84268ms","start":"2026-03-19T17:46:40.782594Z","end":"2026-03-19T17:46:40.946436Z","steps":["trace[1940476643] 'read index received' (duration: 163.709515ms)","trace[1940476643] 'applied index is now lower than readState.Index' (duration: 132.505µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:40.947668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.04515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-19T17:46:40.947775Z","caller":"traceutil/trace.go:171","msg":"trace[597857094] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:210; }","duration":"165.143053ms","start":"2026-03-19T17:46:40.782588Z","end":"2026-03-19T17:46:40.947731Z","steps":["trace[597857094] 'agreement among raft nodes before linearized reading' (duration: 163.968854ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:41.318318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.937935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:41.318764Z","caller":"traceutil/trace.go:171","msg":"trace[1719197143] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"534.920322ms","start":"2026-03-19T17:46:40.7838Z","end":"2026-03-19T17:46:41.318721Z","steps":["trace[1719197143] 'process raft request' (duration: 304.338836ms)","trace[1719197143] 'compare' (duration: 229.817411ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:41.318853Z","caller":"traceutil/trace.go:171","msg":"trace[1114866021] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"369.917793ms","start":"2026-03-19T17:46:40.948918Z","end":"2026-03-19T17:46:41.318836Z","steps":["trace[1114866021] 'process raft request' (duration: 369.552901ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:41.318934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:40.948893Z","time spent":"370.006607ms","remote":"127.0.0.1:51312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T17:46:41.318967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:40.783783Z","time spent":"535.062287ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:41.319143Z","caller":"traceutil/trace.go:171","msg":"trace[2082114281] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"534.861219ms","start":"2026-03-19T17:46:40.784267Z","end":"2026-03-19T17:46:41.319129Z","steps":["trace[2082114281] 'process raft request' (duration: 534.147086ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:41.319193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:40.78425Z","time spent":"534.918732ms","remote":"127.0.0.1:51250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1069,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:46:41.319533Z","caller":"traceutil/trace.go:171","msg":"trace[450613074] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"367.178262ms","start":"2026-03-19T17:46:40.952343Z","end":"2026-03-19T17:46:41.319522Z","steps":["trace[450613074] 'process raft request' (duration: 366.176639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:41.319591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:40.952329Z","time spent":"367.239813ms","remote":"127.0.0.1:51318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":176,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T17:46:41.319812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.60097ms","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-03-19T17:46:41.318578Z","caller":"traceutil/trace.go:171","msg":"trace[1409769254] linearizableReadLoop","detail":"{readStateIndex:219; appliedIndex:215; }","duration":"212.349439ms","start":"2026-03-19T17:46:41.106201Z","end":"2026-03-19T17:46:41.31855Z","steps":["trace[1409769254] 'read index received' (duration: 158.946268ms)","trace[1409769254] 'applied index is now lower than readState.Index' (duration: 53.402211ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:41.319892Z","caller":"traceutil/trace.go:171","msg":"trace[1922913480] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:214; }","duration":"213.718764ms","start":"2026-03-19T17:46:41.106156Z","end":"2026-03-19T17:46:41.319874Z","steps":["trace[1922913480] 'agreement among raft nodes before linearized reading' (duration: 213.510877ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:41.319975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.602021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-03-19T17:46:41.320115Z","caller":"traceutil/trace.go:171","msg":"trace[2032582619] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:214; }","duration":"138.785636ms","start":"2026-03-19T17:46:41.181317Z","end":"2026-03-19T17:46:41.320103Z","steps":["trace[2032582619] 'agreement among raft nodes before linearized reading' (duration: 138.610091ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:41.691152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.89117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:41.691314Z","caller":"traceutil/trace.go:171","msg":"trace[1424532772] linearizableReadLoop","detail":"{readStateIndex:221; appliedIndex:219; }","duration":"367.104698ms","start":"2026-03-19T17:46:41.324194Z","end":"2026-03-19T17:46:41.691298Z","steps":["trace[1424532772] 'read index received' (duration: 21.398734ms)","trace[1424532772] 'applied index is now lower than readState.Index' (duration: 345.704734ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:41.691509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.266633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:41.691532Z","caller":"traceutil/trace.go:171","msg":"trace[572749261] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"365.735782ms","start":"2026-03-19T17:46:41.325769Z","end":"2026-03-19T17:46:41.691505Z","steps":["trace[572749261] 'process raft request' (duration: 365.479403ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:41.69159Z","caller":"traceutil/trace.go:171","msg":"trace[1672937944] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:216; }","duration":"367.426699ms","start":"2026-03-19T17:46:41.324149Z","end":"2026-03-19T17:46:41.691576Z","steps":["trace[1672937944] 'agreement among raft nodes before linearized reading' (duration: 367.17322ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:41.691635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:41.32414Z","time spent":"367.48574ms","remote":"127.0.0.1:51318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" "} {"level":"info","ts":"2026-03-19T17:46:41.691659Z","caller":"traceutil/trace.go:171","msg":"trace[1830495297] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"367.603424ms","start":"2026-03-19T17:46:41.324018Z","end":"2026-03-19T17:46:41.691621Z","steps":["trace[1830495297] 'process raft request' (duration: 156.161576ms)","trace[1830495297] 'compare' (duration: 210.766006ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:41.691675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:41.325759Z","time spent":"365.836626ms","remote":"127.0.0.1:51410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":342,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T17:46:41.691752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:41.323994Z","time spent":"367.714188ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:41.706399Z","caller":"traceutil/trace.go:171","msg":"trace[1332315435] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"357.393764ms","start":"2026-03-19T17:46:41.348982Z","end":"2026-03-19T17:46:41.706375Z","steps":["trace[1332315435] 'process raft request' (duration: 357.26323ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:41.706484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:41.348962Z","time spent":"357.480898ms","remote":"127.0.0.1:51168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:41.706509Z","caller":"traceutil/trace.go:171","msg":"trace[2016196297] transaction","detail":"{read_only:false; response_revision:218; number_of_response:1; }","duration":"352.410228ms","start":"2026-03-19T17:46:41.354057Z","end":"2026-03-19T17:46:41.706467Z","steps":["trace[2016196297] 'process raft request' (duration: 352.266073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:41.70678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:41.35405Z","time spent":"352.597134ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:42.012893Z","caller":"traceutil/trace.go:171","msg":"trace[126772188] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"294.595718ms","start":"2026-03-19T17:46:41.718277Z","end":"2026-03-19T17:46:42.012873Z","steps":["trace[126772188] 'process raft request' (duration: 294.552706ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:42.012955Z","caller":"traceutil/trace.go:171","msg":"trace[152732145] transaction","detail":"{read_only:false; response_revision:221; number_of_response:1; }","duration":"298.471987ms","start":"2026-03-19T17:46:41.714449Z","end":"2026-03-19T17:46:42.012921Z","steps":["trace[152732145] 'process raft request' (duration: 258.851498ms)","trace[152732145] 'compare' (duration: 39.3403ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:42.012947Z","caller":"traceutil/trace.go:171","msg":"trace[1926087447] linearizableReadLoop","detail":"{readStateIndex:227; appliedIndex:226; }","duration":"296.930766ms","start":"2026-03-19T17:46:41.715963Z","end":"2026-03-19T17:46:42.012894Z","steps":["trace[1926087447] 'read index received' (duration: 257.451933ms)","trace[1926087447] 'applied index is now lower than readState.Index' (duration: 39.475093ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:42.013264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.144856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-03-19T17:46:42.013328Z","caller":"traceutil/trace.go:171","msg":"trace[482609899] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:222; }","duration":"301.26231ms","start":"2026-03-19T17:46:41.71205Z","end":"2026-03-19T17:46:42.013312Z","steps":["trace[482609899] 'agreement among raft nodes before linearized reading' (duration: 300.95534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:42.013424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:41.712042Z","time spent":"301.365193ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5920,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-03-19T17:46:42.017251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.971182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:42.017307Z","caller":"traceutil/trace.go:171","msg":"trace[2042481019] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:0; response_revision:223; }","duration":"299.057406ms","start":"2026-03-19T17:46:41.718236Z","end":"2026-03-19T17:46:42.017294Z","steps":["trace[2042481019] 'agreement among raft nodes before linearized reading' (duration: 298.953082ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:42.355893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.324127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:42.35615Z","caller":"traceutil/trace.go:171","msg":"trace[745643759] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"322.141335ms","start":"2026-03-19T17:46:42.033992Z","end":"2026-03-19T17:46:42.356133Z","steps":["trace[745643759] 'process raft request' (duration: 322.105715ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:42.356218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:42.033977Z","time spent":"322.217569ms","remote":"127.0.0.1:51426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:46:42.356196Z","caller":"traceutil/trace.go:171","msg":"trace[998489541] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"334.696142ms","start":"2026-03-19T17:46:42.021419Z","end":"2026-03-19T17:46:42.356115Z","steps":["trace[998489541] 'process raft request' (duration: 334.575338ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:42.356449Z","caller":"traceutil/trace.go:171","msg":"trace[1671338782] linearizableReadLoop","detail":"{readStateIndex:231; appliedIndex:229; }","duration":"336.364466ms","start":"2026-03-19T17:46:42.020062Z","end":"2026-03-19T17:46:42.356426Z","steps":["trace[1671338782] 'read index received' (duration: 9.455612ms)","trace[1671338782] 'applied index is now lower than readState.Index' (duration: 326.906014ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:42.35649Z","caller":"traceutil/trace.go:171","msg":"trace[1927321059] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"334.486054ms","start":"2026-03-19T17:46:42.021993Z","end":"2026-03-19T17:46:42.356479Z","steps":["trace[1927321059] 'process raft request' (duration: 334.049269ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:42.356546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.46986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-03-19T17:46:42.356556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:42.021978Z","time spent":"334.535898ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:42.356577Z","caller":"traceutil/trace.go:171","msg":"trace[1830622389] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:228; }","duration":"336.528743ms","start":"2026-03-19T17:46:42.020038Z","end":"2026-03-19T17:46:42.356567Z","steps":["trace[1830622389] 'agreement among raft nodes before linearized reading' (duration: 336.449741ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:42.356616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:42.02003Z","time spent":"336.570335ms","remote":"127.0.0.1:51264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-19T17:46:42.356524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:42.021406Z","time spent":"335.027483ms","remote":"127.0.0.1:51364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":466,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:46:42.356893Z","caller":"traceutil/trace.go:171","msg":"trace[433414929] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"337.219286ms","start":"2026-03-19T17:46:42.019612Z","end":"2026-03-19T17:46:42.356832Z","steps":["trace[433414929] 'process raft request' (duration: 217.883315ms)","trace[433414929] 'compare' (duration: 118.178093ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:42.357044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:42.019596Z","time spent":"337.37727ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T17:46:42.825604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.324295ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:42.825712Z","caller":"traceutil/trace.go:171","msg":"trace[2076254926] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"464.289602ms","start":"2026-03-19T17:46:42.361404Z","end":"2026-03-19T17:46:42.825694Z","steps":["trace[2076254926] 'process raft request' (duration: 172.716149ms)","trace[2076254926] 'compare' (duration: 291.15764ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:42.825777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:42.361383Z","time spent":"464.363486ms","remote":"127.0.0.1:51318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:46:42.873055Z","caller":"traceutil/trace.go:171","msg":"trace[154742983] linearizableReadLoop","detail":"{readStateIndex:237; appliedIndex:234; }","duration":"402.721655ms","start":"2026-03-19T17:46:42.470317Z","end":"2026-03-19T17:46:42.873039Z","steps":["trace[154742983] 'read index received' (duration: 63.839834ms)","trace[154742983] 'applied index is now lower than readState.Index' (duration: 338.880821ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:42.873157Z","caller":"traceutil/trace.go:171","msg":"trace[2028545041] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"413.537792ms","start":"2026-03-19T17:46:42.45961Z","end":"2026-03-19T17:46:42.873148Z","steps":["trace[2028545041] 'process raft request' (duration: 413.348186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:42.873218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:42.459578Z","time spent":"413.600664ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T17:46:42.87336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.044505ms","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-03-19T17:46:42.873396Z","caller":"traceutil/trace.go:171","msg":"trace[944831186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:231; }","duration":"403.109767ms","start":"2026-03-19T17:46:42.470277Z","end":"2026-03-19T17:46:42.873387Z","steps":["trace[944831186] 'agreement among raft nodes before linearized reading' (duration: 403.023444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:42.873422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:42.470264Z","time spent":"403.153148ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-19T17:46:42.87358Z","caller":"traceutil/trace.go:171","msg":"trace[2001067650] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"511.684472ms","start":"2026-03-19T17:46:42.361843Z","end":"2026-03-19T17:46:42.873528Z","steps":["trace[2001067650] 'process raft request' (duration: 511.030711ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:42.873655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.438656ms","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-03-19T17:46:42.873727Z","caller":"traceutil/trace.go:171","msg":"trace[103221133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:231; }","duration":"338.572711ms","start":"2026-03-19T17:46:42.535139Z","end":"2026-03-19T17:46:42.873712Z","steps":["trace[103221133] 'agreement among raft nodes before linearized reading' (duration: 338.354694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:42.873735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:42.361828Z","time spent":"511.833088ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T17:46:42.873772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:42.535116Z","time spent":"338.647643ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T17:46:43.155872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.877514ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:43.156125Z","caller":"traceutil/trace.go:171","msg":"trace[1844195023] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"276.20343ms","start":"2026-03-19T17:46:42.879889Z","end":"2026-03-19T17:46:43.156093Z","steps":["trace[1844195023] 'process raft request' (duration: 92.012996ms)","trace[1844195023] 'compare' (duration: 183.714039ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:43.15616Z","caller":"traceutil/trace.go:171","msg":"trace[824635827] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"275.811419ms","start":"2026-03-19T17:46:42.880331Z","end":"2026-03-19T17:46:43.156142Z","steps":["trace[824635827] 'process raft request' (duration: 275.646743ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:43.156285Z","caller":"traceutil/trace.go:171","msg":"trace[1133208506] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"274.097532ms","start":"2026-03-19T17:46:42.882179Z","end":"2026-03-19T17:46:43.156276Z","steps":["trace[1133208506] 'process raft request' (duration: 273.859954ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:43.156289Z","caller":"traceutil/trace.go:171","msg":"trace[1694389133] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"235.151993ms","start":"2026-03-19T17:46:42.921129Z","end":"2026-03-19T17:46:43.156281Z","steps":["trace[1694389133] 'process raft request' (duration: 235.008378ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:43.156401Z","caller":"traceutil/trace.go:171","msg":"trace[634338611] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"233.269359ms","start":"2026-03-19T17:46:42.923113Z","end":"2026-03-19T17:46:43.156383Z","steps":["trace[634338611] 'process raft request' (duration: 233.100703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:44.258522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.90683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:44.25863Z","caller":"traceutil/trace.go:171","msg":"trace[216371695] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"1.035560902s","start":"2026-03-19T17:46:43.223043Z","end":"2026-03-19T17:46:44.258604Z","steps":["trace[216371695] 'process raft request' (duration: 291.477115ms)","trace[216371695] 'compare' (duration: 743.432874ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:44.258695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:43.223027Z","time spent":"1.035641594s","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:44.306082Z","caller":"traceutil/trace.go:171","msg":"trace[2011198126] linearizableReadLoop","detail":"{readStateIndex:248; appliedIndex:246; }","duration":"1.081936446s","start":"2026-03-19T17:46:43.224118Z","end":"2026-03-19T17:46:44.306054Z","steps":["trace[2011198126] 'read index received' (duration: 290.418392ms)","trace[2011198126] 'applied index is now lower than readState.Index' (duration: 791.516724ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:44.306161Z","caller":"traceutil/trace.go:171","msg":"trace[878332901] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"1.082408912s","start":"2026-03-19T17:46:43.223724Z","end":"2026-03-19T17:46:44.306133Z","steps":["trace[878332901] 'process raft request' (duration: 1.082179325s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:44.306402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082260007s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-03-19T17:46:44.306459Z","caller":"traceutil/trace.go:171","msg":"trace[795001624] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:242; }","duration":"1.082332519s","start":"2026-03-19T17:46:43.224112Z","end":"2026-03-19T17:46:44.306445Z","steps":["trace[795001624] 'agreement among raft nodes before linearized reading' (duration: 1.082078991s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:44.306439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:43.223709Z","time spent":"1.082602888s","remote":"127.0.0.1:51236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11428,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T17:46:44.30651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:43.224081Z","time spent":"1.082417572s","remote":"127.0.0.1:51318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":228,"request content":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" "} {"level":"warn","ts":"2026-03-19T17:46:44.65828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.125876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:44.658581Z","caller":"traceutil/trace.go:171","msg":"trace[2065083266] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"202.983651ms","start":"2026-03-19T17:46:44.455575Z","end":"2026-03-19T17:46:44.658559Z","steps":["trace[2065083266] 'process raft request' (duration: 202.91876ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:44.658801Z","caller":"traceutil/trace.go:171","msg":"trace[183524257] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"347.489292ms","start":"2026-03-19T17:46:44.311301Z","end":"2026-03-19T17:46:44.658791Z","steps":["trace[183524257] 'process raft request' (duration: 347.11207ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:44.65886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:44.311276Z","time spent":"347.557945ms","remote":"127.0.0.1:51410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":324,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:46:44.65892Z","caller":"traceutil/trace.go:171","msg":"trace[1483085882] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"622.074543ms","start":"2026-03-19T17:46:44.036812Z","end":"2026-03-19T17:46:44.658886Z","steps":["trace[1483085882] 'process raft request' (duration: 519.261975ms)","trace[1483085882] 'compare' (duration: 101.9514ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:44.659097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:44.036791Z","time spent":"622.217398ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:46:44.659117Z","caller":"traceutil/trace.go:171","msg":"trace[203280586] linearizableReadLoop","detail":"{readStateIndex:249; appliedIndex:248; }","duration":"352.93987ms","start":"2026-03-19T17:46:44.306157Z","end":"2026-03-19T17:46:44.659097Z","steps":["trace[203280586] 'read index received' (duration: 249.907475ms)","trace[203280586] 'applied index is now lower than readState.Index' (duration: 103.031145ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:44.659249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.326688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-19T17:46:44.659279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.335080125s","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-03-19T17:46:44.659292Z","caller":"traceutil/trace.go:171","msg":"trace[1789817146] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:245; }","duration":"963.410651ms","start":"2026-03-19T17:46:43.695871Z","end":"2026-03-19T17:46:44.659282Z","steps":["trace[1789817146] 'agreement among raft nodes before linearized reading' (duration: 963.329598ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:44.659312Z","caller":"traceutil/trace.go:171","msg":"trace[44716982] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:245; }","duration":"1.335157837s","start":"2026-03-19T17:46:43.324144Z","end":"2026-03-19T17:46:44.659302Z","steps":["trace[44716982] 'agreement among raft nodes before linearized reading' (duration: 1.335075264s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:44.65933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:43.695844Z","time spent":"963.479393ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T17:46:44.659345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:43.324128Z","time spent":"1.335209349s","remote":"127.0.0.1:51360","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-03-19T17:46:44.65943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.418063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.189e4f34b9787237\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-03-19T17:46:44.659488Z","caller":"traceutil/trace.go:171","msg":"trace[628582518] range","detail":"{range_begin:/registry/events/default/instance.189e4f34b9787237; range_end:; response_count:1; response_revision:245; }","duration":"397.519537ms","start":"2026-03-19T17:46:44.261956Z","end":"2026-03-19T17:46:44.659476Z","steps":["trace[628582518] 'agreement among raft nodes before linearized reading' (duration: 397.412573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:44.659529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:44.261934Z","time spent":"397.584449ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":674,"request content":"key:\"/registry/events/default/instance.189e4f34b9787237\" "} {"level":"warn","ts":"2026-03-19T17:46:44.659614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.377859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"warn","ts":"2026-03-19T17:46:44.65956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.003466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:44.659791Z","caller":"traceutil/trace.go:171","msg":"trace[1652931718] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:0; response_revision:245; }","duration":"339.270303ms","start":"2026-03-19T17:46:44.320489Z","end":"2026-03-19T17:46:44.65976Z","steps":["trace[1652931718] 'agreement among raft nodes before linearized reading' (duration: 338.942123ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:44.659721Z","caller":"traceutil/trace.go:171","msg":"trace[1080193284] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:245; }","duration":"624.528503ms","start":"2026-03-19T17:46:44.035175Z","end":"2026-03-19T17:46:44.659703Z","steps":["trace[1080193284] 'agreement among raft nodes before linearized reading' (duration: 623.934863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:44.661646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:44.035159Z","time spent":"626.464447ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":5536,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-03-19T17:46:44.662092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:44.320478Z","time spent":"341.57505ms","remote":"127.0.0.1:51318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"info","ts":"2026-03-19T17:46:44.836214Z","caller":"traceutil/trace.go:171","msg":"trace[1370407906] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"170.18572ms","start":"2026-03-19T17:46:44.665997Z","end":"2026-03-19T17:46:44.836182Z","steps":["trace[1370407906] 'process raft request' (duration: 136.676284ms)","trace[1370407906] 'compare' (duration: 33.261507ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:44.838494Z","caller":"traceutil/trace.go:171","msg":"trace[673212835] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"170.80553ms","start":"2026-03-19T17:46:44.667675Z","end":"2026-03-19T17:46:44.83848Z","steps":["trace[673212835] 'process raft request' (duration: 170.740087ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:44.838916Z","caller":"traceutil/trace.go:171","msg":"trace[948373971] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"171.157132ms","start":"2026-03-19T17:46:44.667745Z","end":"2026-03-19T17:46:44.838902Z","steps":["trace[948373971] 'process raft request' (duration: 170.708267ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:44.839006Z","caller":"traceutil/trace.go:171","msg":"trace[1963991947] linearizableReadLoop","detail":"{readStateIndex:255; appliedIndex:251; }","duration":"170.905584ms","start":"2026-03-19T17:46:44.668079Z","end":"2026-03-19T17:46:44.838985Z","steps":["trace[1963991947] 'read index received' (duration: 134.528665ms)","trace[1963991947] 'applied index is now lower than readState.Index' (duration: 36.375349ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:44.839143Z","caller":"traceutil/trace.go:171","msg":"trace[911028973] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"170.933554ms","start":"2026-03-19T17:46:44.66819Z","end":"2026-03-19T17:46:44.839124Z","steps":["trace[911028973] 'process raft request' (duration: 170.575282ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:44.839452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.350918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3690"} {"level":"info","ts":"2026-03-19T17:46:44.839498Z","caller":"traceutil/trace.go:171","msg":"trace[1301130019] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:251; }","duration":"171.43403ms","start":"2026-03-19T17:46:44.668054Z","end":"2026-03-19T17:46:44.839488Z","steps":["trace[1301130019] 'agreement among raft nodes before linearized reading' (duration: 171.340447ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:44.839584Z","caller":"traceutil/trace.go:171","msg":"trace[1327642994] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"167.172971ms","start":"2026-03-19T17:46:44.672403Z","end":"2026-03-19T17:46:44.839576Z","steps":["trace[1327642994] 'process raft request' (duration: 166.50195ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:44.840182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.738608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-19T17:46:44.840303Z","caller":"traceutil/trace.go:171","msg":"trace[775607695] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:251; }","duration":"171.391199ms","start":"2026-03-19T17:46:44.668849Z","end":"2026-03-19T17:46:44.840241Z","steps":["trace[775607695] 'agreement among raft nodes before linearized reading' (duration: 170.667856ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:44.840695Z","caller":"traceutil/trace.go:171","msg":"trace[1211259118] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"172.02508ms","start":"2026-03-19T17:46:44.666493Z","end":"2026-03-19T17:46:44.838518Z","steps":["trace[1211259118] 'process raft request' (duration: 171.805923ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:45.557739Z","caller":"traceutil/trace.go:171","msg":"trace[2102382605] linearizableReadLoop","detail":"{readStateIndex:279; appliedIndex:278; }","duration":"191.231906ms","start":"2026-03-19T17:46:45.36648Z","end":"2026-03-19T17:46:45.557712Z","steps":["trace[2102382605] 'read index received' (duration: 191.004328ms)","trace[2102382605] 'applied index is now lower than readState.Index' (duration: 226.508µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:45.5578Z","caller":"traceutil/trace.go:171","msg":"trace[1804743514] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"194.076829ms","start":"2026-03-19T17:46:45.363686Z","end":"2026-03-19T17:46:45.557762Z","steps":["trace[1804743514] 'process raft request' (duration: 193.834791ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:45.557964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.461183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.189e4f34b9787237\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-03-19T17:46:45.558011Z","caller":"traceutil/trace.go:171","msg":"trace[211403247] range","detail":"{range_begin:/registry/events/default/instance.189e4f34b9787237; range_end:; response_count:1; response_revision:273; }","duration":"191.542947ms","start":"2026-03-19T17:46:45.366456Z","end":"2026-03-19T17:46:45.557999Z","steps":["trace[211403247] 'agreement among raft nodes before linearized reading' (duration: 191.439434ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:45.558083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.382692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-03-19T17:46:45.558177Z","caller":"traceutil/trace.go:171","msg":"trace[199598329] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:273; }","duration":"191.536116ms","start":"2026-03-19T17:46:45.366621Z","end":"2026-03-19T17:46:45.558157Z","steps":["trace[199598329] 'agreement among raft nodes before linearized reading' (duration: 191.324539ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:45.787049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.116372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:45.787246Z","caller":"traceutil/trace.go:171","msg":"trace[2069845038] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:279; }","duration":"226.056826ms","start":"2026-03-19T17:46:45.561173Z","end":"2026-03-19T17:46:45.78723Z","steps":["trace[2069845038] 'read index received' (duration: 103.756148ms)","trace[2069845038] 'applied index is now lower than readState.Index' (duration: 122.296708ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:45.787273Z","caller":"traceutil/trace.go:171","msg":"trace[1873142766] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"226.589484ms","start":"2026-03-19T17:46:45.560662Z","end":"2026-03-19T17:46:45.787252Z","steps":["trace[1873142766] 'process raft request' (duration: 104.167191ms)","trace[1873142766] 'compare' (duration: 121.997619ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:45.787473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.271513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-03-19T17:46:45.787565Z","caller":"traceutil/trace.go:171","msg":"trace[253132221] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:274; }","duration":"226.406447ms","start":"2026-03-19T17:46:45.561135Z","end":"2026-03-19T17:46:45.787541Z","steps":["trace[253132221] 'agreement among raft nodes before linearized reading' (duration: 226.18008ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:45.787526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.097538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-lwf1fi\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:45.788221Z","caller":"traceutil/trace.go:171","msg":"trace[704004154] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-lwf1fi; range_end:; response_count:0; response_revision:274; }","duration":"226.805851ms","start":"2026-03-19T17:46:45.561373Z","end":"2026-03-19T17:46:45.788179Z","steps":["trace[704004154] 'agreement among raft nodes before linearized reading' (duration: 225.988175ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:46.064051Z","caller":"traceutil/trace.go:171","msg":"trace[687698792] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"272.688147ms","start":"2026-03-19T17:46:45.791328Z","end":"2026-03-19T17:46:46.064016Z","steps":["trace[687698792] 'process raft request' (duration: 231.925362ms)","trace[687698792] 'compare' (duration: 40.650661ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:46.064329Z","caller":"traceutil/trace.go:171","msg":"trace[1315395572] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"271.744275ms","start":"2026-03-19T17:46:45.792555Z","end":"2026-03-19T17:46:46.0643Z","steps":["trace[1315395572] 'process raft request' (duration: 271.508848ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:46.064751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.627402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:46.064827Z","caller":"traceutil/trace.go:171","msg":"trace[1079465244] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:0; response_revision:276; }","duration":"271.745536ms","start":"2026-03-19T17:46:45.793064Z","end":"2026-03-19T17:46:46.06481Z","steps":["trace[1079465244] 'agreement among raft nodes before linearized reading' (duration: 271.355683ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:46.064344Z","caller":"traceutil/trace.go:171","msg":"trace[1377584014] linearizableReadLoop","detail":"{readStateIndex:282; appliedIndex:280; }","duration":"271.065414ms","start":"2026-03-19T17:46:45.793096Z","end":"2026-03-19T17:46:46.064161Z","steps":["trace[1377584014] 'read index received' (duration: 230.252358ms)","trace[1377584014] 'applied index is now lower than readState.Index' (duration: 40.812336ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:46.331878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.648196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:46.332108Z","caller":"traceutil/trace.go:171","msg":"trace[1731300871] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"178.32461ms","start":"2026-03-19T17:46:46.153764Z","end":"2026-03-19T17:46:46.332088Z","steps":["trace[1731300871] 'process raft request' (duration: 178.289188ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:46.332125Z","caller":"traceutil/trace.go:171","msg":"trace[276162194] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"178.772684ms","start":"2026-03-19T17:46:46.153326Z","end":"2026-03-19T17:46:46.332099Z","steps":["trace[276162194] 'process raft request' (duration: 178.65273ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:46.332527Z","caller":"traceutil/trace.go:171","msg":"trace[420040421] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"184.458698ms","start":"2026-03-19T17:46:46.14806Z","end":"2026-03-19T17:46:46.332518Z","steps":["trace[420040421] 'process raft request' (duration: 29.090868ms)","trace[420040421] 'compare' (duration: 154.433519ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:46.619963Z","caller":"traceutil/trace.go:171","msg":"trace[1508699539] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"120.611029ms","start":"2026-03-19T17:46:46.499328Z","end":"2026-03-19T17:46:46.619939Z","steps":["trace[1508699539] 'process raft request' (duration: 120.448684ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:46.619955Z","caller":"traceutil/trace.go:171","msg":"trace[1288394719] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"122.160319ms","start":"2026-03-19T17:46:46.497752Z","end":"2026-03-19T17:46:46.619912Z","steps":["trace[1288394719] 'process raft request' (duration: 109.513041ms)","trace[1288394719] 'compare' (duration: 12.375419ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:47.899279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.737168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:47.899364Z","caller":"traceutil/trace.go:171","msg":"trace[859407527] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:0; response_revision:346; }","duration":"125.903483ms","start":"2026-03-19T17:46:47.773447Z","end":"2026-03-19T17:46:47.89935Z","steps":["trace[859407527] 'range keys from in-memory index tree' (duration: 125.569203ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:48.277881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.222451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:48.277986Z","caller":"traceutil/trace.go:171","msg":"trace[1744661115] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:359; }","duration":"134.546712ms","start":"2026-03-19T17:46:48.143422Z","end":"2026-03-19T17:46:48.277968Z","steps":["trace[1744661115] 'read index received' (duration: 6.216808ms)","trace[1744661115] 'applied index is now lower than readState.Index' (duration: 128.328794ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:48.278048Z","caller":"traceutil/trace.go:171","msg":"trace[1714710410] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"147.140014ms","start":"2026-03-19T17:46:48.130875Z","end":"2026-03-19T17:46:48.278015Z","steps":["trace[1714710410] 'process raft request' (duration: 18.701625ms)","trace[1714710410] 'compare' (duration: 128.066047ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:48.278157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.724288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7111"} {"level":"info","ts":"2026-03-19T17:46:48.278197Z","caller":"traceutil/trace.go:171","msg":"trace[128335238] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:349; }","duration":"134.805561ms","start":"2026-03-19T17:46:48.14338Z","end":"2026-03-19T17:46:48.278186Z","steps":["trace[128335238] 'agreement among raft nodes before linearized reading' (duration: 134.667457ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:48.278673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.303942ms","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-03-19T17:46:48.278811Z","caller":"traceutil/trace.go:171","msg":"trace[1517515089] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:349; }","duration":"105.513838ms","start":"2026-03-19T17:46:48.173275Z","end":"2026-03-19T17:46:48.278789Z","steps":["trace[1517515089] 'agreement among raft nodes before linearized reading' (duration: 105.210088ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:48.279037Z","caller":"traceutil/trace.go:171","msg":"trace[2142430516] transaction","detail":"{read_only:false; number_of_response:0; response_revision:349; }","duration":"133.614054ms","start":"2026-03-19T17:46:48.145407Z","end":"2026-03-19T17:46:48.279021Z","steps":["trace[2142430516] 'process raft request' (duration: 132.86756ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:48.477526Z","caller":"traceutil/trace.go:171","msg":"trace[794968991] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:363; }","duration":"154.109685ms","start":"2026-03-19T17:46:48.323399Z","end":"2026-03-19T17:46:48.477509Z","steps":["trace[794968991] 'read index received' (duration: 34.103206ms)","trace[794968991] 'applied index is now lower than readState.Index' (duration: 120.005749ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:48.477631Z","caller":"traceutil/trace.go:171","msg":"trace[424322839] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"184.634927ms","start":"2026-03-19T17:46:48.292962Z","end":"2026-03-19T17:46:48.477597Z","steps":["trace[424322839] 'process raft request' (duration: 102.617756ms)","trace[424322839] 'compare' (duration: 81.841775ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:48.477722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.665183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6755"} {"level":"info","ts":"2026-03-19T17:46:48.477761Z","caller":"traceutil/trace.go:171","msg":"trace[1528229253] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:352; }","duration":"116.747386ms","start":"2026-03-19T17:46:48.361009Z","end":"2026-03-19T17:46:48.477756Z","steps":["trace[1528229253] 'agreement among raft nodes before linearized reading' (duration: 116.696275ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:48.477686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.27313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-03-19T17:46:48.477861Z","caller":"traceutil/trace.go:171","msg":"trace[1680713380] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:352; }","duration":"154.457957ms","start":"2026-03-19T17:46:48.323375Z","end":"2026-03-19T17:46:48.477833Z","steps":["trace[1680713380] 'agreement among raft nodes before linearized reading' (duration: 154.24617ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:48.478022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.075314ms","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-03-19T17:46:48.478053Z","caller":"traceutil/trace.go:171","msg":"trace[955210234] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:352; }","duration":"105.137086ms","start":"2026-03-19T17:46:48.372908Z","end":"2026-03-19T17:46:48.478045Z","steps":["trace[955210234] 'agreement among raft nodes before linearized reading' (duration: 105.051324ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:48.725764Z","caller":"traceutil/trace.go:171","msg":"trace[1268896139] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"169.920738ms","start":"2026-03-19T17:46:48.55582Z","end":"2026-03-19T17:46:48.725741Z","steps":["trace[1268896139] 'process raft request' (duration: 109.210876ms)","trace[1268896139] 'compare' (duration: 60.587649ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:48.73338Z","caller":"traceutil/trace.go:171","msg":"trace[1372499813] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:366; }","duration":"159.369802ms","start":"2026-03-19T17:46:48.573997Z","end":"2026-03-19T17:46:48.733367Z","steps":["trace[1372499813] 'read index received' (duration: 91.045517ms)","trace[1372499813] 'applied index is now lower than readState.Index' (duration: 68.323645ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:48.733647Z","caller":"traceutil/trace.go:171","msg":"trace[540537991] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"176.826948ms","start":"2026-03-19T17:46:48.556811Z","end":"2026-03-19T17:46:48.733638Z","steps":["trace[540537991] 'process raft request' (duration: 176.479897ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:48.733959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.94327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-03-19T17:46:48.733992Z","caller":"traceutil/trace.go:171","msg":"trace[2003691675] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:356; }","duration":"160.025922ms","start":"2026-03-19T17:46:48.573959Z","end":"2026-03-19T17:46:48.733985Z","steps":["trace[2003691675] 'agreement among raft nodes before linearized reading' (duration: 159.95152ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:48.734231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.091915ms","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-03-19T17:46:48.73426Z","caller":"traceutil/trace.go:171","msg":"trace[304382236] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:356; }","duration":"110.153456ms","start":"2026-03-19T17:46:48.6241Z","end":"2026-03-19T17:46:48.734253Z","steps":["trace[304382236] 'agreement among raft nodes before linearized reading' (duration: 110.107985ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.104519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.858981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:49.104623Z","caller":"traceutil/trace.go:171","msg":"trace[163379505] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"335.093366ms","start":"2026-03-19T17:46:48.769513Z","end":"2026-03-19T17:46:49.104606Z","steps":["trace[163379505] 'process raft request' (duration: 102.065609ms)","trace[163379505] 'compare' (duration: 232.478859ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:49.104679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:48.769497Z","time spent":"335.155017ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:49.156001Z","caller":"traceutil/trace.go:171","msg":"trace[1663812990] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:372; }","duration":"379.893901ms","start":"2026-03-19T17:46:48.776086Z","end":"2026-03-19T17:46:49.15598Z","steps":["trace[1663812990] 'read index received' (duration: 95.466069ms)","trace[1663812990] 'applied index is now lower than readState.Index' (duration: 284.426922ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:49.156281Z","caller":"traceutil/trace.go:171","msg":"trace[429816095] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"382.007538ms","start":"2026-03-19T17:46:48.774118Z","end":"2026-03-19T17:46:49.156125Z","steps":["trace[429816095] 'process raft request' (duration: 381.815121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.156387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.1312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-03-19T17:46:49.156467Z","caller":"traceutil/trace.go:171","msg":"trace[1402049944] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:363; }","duration":"332.240424ms","start":"2026-03-19T17:46:48.824206Z","end":"2026-03-19T17:46:49.156447Z","steps":["trace[1402049944] 'agreement among raft nodes before linearized reading' (duration: 332.067919ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.156484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:48.774108Z","time spent":"382.273306ms","remote":"127.0.0.1:51570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:46:49.156483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.000817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-19T17:46:49.156506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:48.824195Z","time spent":"332.301986ms","remote":"127.0.0.1:51318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"info","ts":"2026-03-19T17:46:49.156543Z","caller":"traceutil/trace.go:171","msg":"trace[725059182] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:363; }","duration":"218.109491ms","start":"2026-03-19T17:46:48.938418Z","end":"2026-03-19T17:46:49.156528Z","steps":["trace[725059182] 'agreement among raft nodes before linearized reading' (duration: 217.971786ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.156525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.872119ms","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-03-19T17:46:49.156591Z","caller":"traceutil/trace.go:171","msg":"trace[446424386] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:363; }","duration":"231.982493ms","start":"2026-03-19T17:46:48.924594Z","end":"2026-03-19T17:46:49.156577Z","steps":["trace[446424386] 'agreement among raft nodes before linearized reading' (duration: 231.817218ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.156656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.388049ms","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-03-19T17:46:49.156691Z","caller":"traceutil/trace.go:171","msg":"trace[1190238032] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:363; }","duration":"133.457391ms","start":"2026-03-19T17:46:49.023224Z","end":"2026-03-19T17:46:49.156681Z","steps":["trace[1190238032] 'agreement among raft nodes before linearized reading' (duration: 133.066829ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.156726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.50944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-19T17:46:49.156754Z","caller":"traceutil/trace.go:171","msg":"trace[1042097957] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:363; }","duration":"183.573322ms","start":"2026-03-19T17:46:48.973173Z","end":"2026-03-19T17:46:49.156747Z","steps":["trace[1042097957] 'agreement among raft nodes before linearized reading' (duration: 183.530491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.156774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.659595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-03-19T17:46:49.15686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.908961ms","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-03-19T17:46:49.156871Z","caller":"traceutil/trace.go:171","msg":"trace[188830772] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:363; }","duration":"380.786079ms","start":"2026-03-19T17:46:48.776061Z","end":"2026-03-19T17:46:49.156847Z","steps":["trace[188830772] 'agreement among raft nodes before linearized reading' (duration: 380.651495ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.156891Z","caller":"traceutil/trace.go:171","msg":"trace[121173630] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:363; }","duration":"215.954742ms","start":"2026-03-19T17:46:48.940925Z","end":"2026-03-19T17:46:49.15688Z","steps":["trace[121173630] 'agreement among raft nodes before linearized reading' (duration: 215.911681ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.156923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:48.776048Z","time spent":"380.86171ms","remote":"127.0.0.1:51318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-03-19T17:46:49.156807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.155049ms","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-03-19T17:46:49.157064Z","caller":"traceutil/trace.go:171","msg":"trace[1078356006] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:363; }","duration":"282.459199ms","start":"2026-03-19T17:46:48.874594Z","end":"2026-03-19T17:46:49.157053Z","steps":["trace[1078356006] 'agreement among raft nodes before linearized reading' (duration: 282.17915ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.166264Z","caller":"traceutil/trace.go:171","msg":"trace[1071841656] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"382.449391ms","start":"2026-03-19T17:46:48.773793Z","end":"2026-03-19T17:46:49.156243Z","steps":["trace[1071841656] 'process raft request' (duration: 381.982367ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.166365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:48.773779Z","time spent":"392.532091ms","remote":"127.0.0.1:51354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2267,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:49.374679Z","caller":"traceutil/trace.go:171","msg":"trace[2033151169] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"207.437361ms","start":"2026-03-19T17:46:49.167214Z","end":"2026-03-19T17:46:49.374651Z","steps":["trace[2033151169] 'process raft request' (duration: 142.626712ms)","trace[2033151169] 'compare' (duration: 64.641364ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:49.376115Z","caller":"traceutil/trace.go:171","msg":"trace[55059977] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:375; }","duration":"200.236294ms","start":"2026-03-19T17:46:49.175861Z","end":"2026-03-19T17:46:49.376097Z","steps":["trace[55059977] 'read index received' (duration: 133.985449ms)","trace[55059977] 'applied index is now lower than readState.Index' (duration: 66.250015ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:49.376541Z","caller":"traceutil/trace.go:171","msg":"trace[1024313294] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"209.032181ms","start":"2026-03-19T17:46:49.167496Z","end":"2026-03-19T17:46:49.376528Z","steps":["trace[1024313294] 'process raft request' (duration: 208.029428ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.376598Z","caller":"traceutil/trace.go:171","msg":"trace[1985047410] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"202.499734ms","start":"2026-03-19T17:46:49.174083Z","end":"2026-03-19T17:46:49.376583Z","steps":["trace[1985047410] 'process raft request' (duration: 201.701179ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.376841Z","caller":"traceutil/trace.go:171","msg":"trace[894926987] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"202.754352ms","start":"2026-03-19T17:46:49.174075Z","end":"2026-03-19T17:46:49.37683Z","steps":["trace[894926987] 'process raft request' (duration: 201.75496ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.376878Z","caller":"traceutil/trace.go:171","msg":"trace[1462617496] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"209.156465ms","start":"2026-03-19T17:46:49.167711Z","end":"2026-03-19T17:46:49.376867Z","steps":["trace[1462617496] 'process raft request' (duration: 207.891515ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.377038Z","caller":"traceutil/trace.go:171","msg":"trace[750681336] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"209.272509ms","start":"2026-03-19T17:46:49.167755Z","end":"2026-03-19T17:46:49.377028Z","steps":["trace[750681336] 'process raft request' (duration: 207.883565ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.377258Z","caller":"traceutil/trace.go:171","msg":"trace[2094278841] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"209.31498ms","start":"2026-03-19T17:46:49.167934Z","end":"2026-03-19T17:46:49.377249Z","steps":["trace[2094278841] 'process raft request' (duration: 207.753381ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.37739Z","caller":"traceutil/trace.go:171","msg":"trace[960587741] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"207.486433ms","start":"2026-03-19T17:46:49.169896Z","end":"2026-03-19T17:46:49.377382Z","steps":["trace[960587741] 'process raft request' (duration: 205.82891ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.377583Z","caller":"traceutil/trace.go:171","msg":"trace[1943983369] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"203.576129ms","start":"2026-03-19T17:46:49.173977Z","end":"2026-03-19T17:46:49.377553Z","steps":["trace[1943983369] 'process raft request' (duration: 201.775792ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.377716Z","caller":"traceutil/trace.go:171","msg":"trace[1734178137] transaction","detail":"{read_only:false; number_of_response:1; response_revision:372; }","duration":"202.773594ms","start":"2026-03-19T17:46:49.174246Z","end":"2026-03-19T17:46:49.37702Z","steps":["trace[1734178137] 'process raft request' (duration: 201.663479ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.377912Z","caller":"traceutil/trace.go:171","msg":"trace[448229855] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"203.6516ms","start":"2026-03-19T17:46:49.174251Z","end":"2026-03-19T17:46:49.377902Z","steps":["trace[448229855] 'process raft request' (duration: 201.713599ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.378173Z","caller":"traceutil/trace.go:171","msg":"trace[1155641906] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"203.796285ms","start":"2026-03-19T17:46:49.174367Z","end":"2026-03-19T17:46:49.378163Z","steps":["trace[1155641906] 'process raft request' (duration: 201.652597ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.378298Z","caller":"traceutil/trace.go:171","msg":"trace[24496905] transaction","detail":"{read_only:false; number_of_response:1; response_revision:374; }","duration":"196.337229ms","start":"2026-03-19T17:46:49.181949Z","end":"2026-03-19T17:46:49.378286Z","steps":["trace[24496905] 'process raft request' (duration: 194.116088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.378505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.625219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5164"} {"level":"info","ts":"2026-03-19T17:46:49.37855Z","caller":"traceutil/trace.go:171","msg":"trace[1686435244] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:374; }","duration":"202.712761ms","start":"2026-03-19T17:46:49.175827Z","end":"2026-03-19T17:46:49.37854Z","steps":["trace[1686435244] 'agreement among raft nodes before linearized reading' (duration: 202.562606ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.378719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.730951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-19T17:46:49.378756Z","caller":"traceutil/trace.go:171","msg":"trace[1138060573] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:374; }","duration":"202.781903ms","start":"2026-03-19T17:46:49.175965Z","end":"2026-03-19T17:46:49.378747Z","steps":["trace[1138060573] 'agreement among raft nodes before linearized reading' (duration: 202.720352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.379003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.942889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6755"} {"level":"warn","ts":"2026-03-19T17:46:49.379022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.396825ms","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-03-19T17:46:49.379041Z","caller":"traceutil/trace.go:171","msg":"trace[1425739248] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:374; }","duration":"197.000891ms","start":"2026-03-19T17:46:49.182031Z","end":"2026-03-19T17:46:49.379032Z","steps":["trace[1425739248] 'agreement among raft nodes before linearized reading' (duration: 196.933138ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.379061Z","caller":"traceutil/trace.go:171","msg":"trace[1195718450] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:374; }","duration":"155.478638ms","start":"2026-03-19T17:46:49.223572Z","end":"2026-03-19T17:46:49.379051Z","steps":["trace[1195718450] 'agreement among raft nodes before linearized reading' (duration: 155.382295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.379184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.128804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-03-19T17:46:49.379219Z","caller":"traceutil/trace.go:171","msg":"trace[250947725] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:374; }","duration":"197.195867ms","start":"2026-03-19T17:46:49.182014Z","end":"2026-03-19T17:46:49.37921Z","steps":["trace[250947725] 'agreement among raft nodes before linearized reading' (duration: 197.131544ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.835541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.168894ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:49.83583Z","caller":"traceutil/trace.go:171","msg":"trace[1209498314] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"450.965842ms","start":"2026-03-19T17:46:49.384828Z","end":"2026-03-19T17:46:49.835794Z","steps":["trace[1209498314] 'process raft request' (duration: 334.443257ms)","trace[1209498314] 'compare' (duration: 115.847334ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:49.835992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:49.384811Z","time spent":"451.100666ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:49.836237Z","caller":"traceutil/trace.go:171","msg":"trace[1823275509] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"449.26556ms","start":"2026-03-19T17:46:49.386939Z","end":"2026-03-19T17:46:49.836205Z","steps":["trace[1823275509] 'process raft request' (duration: 448.944089ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.836329Z","caller":"traceutil/trace.go:171","msg":"trace[617659396] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"442.488655ms","start":"2026-03-19T17:46:49.393828Z","end":"2026-03-19T17:46:49.836316Z","steps":["trace[617659396] 'process raft request' (duration: 442.32282ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.836343Z","caller":"traceutil/trace.go:171","msg":"trace[1349908674] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"443.725973ms","start":"2026-03-19T17:46:49.392584Z","end":"2026-03-19T17:46:49.83631Z","steps":["trace[1349908674] 'process raft request' (duration: 443.423283ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.836362Z","caller":"traceutil/trace.go:171","msg":"trace[543380730] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"447.650138ms","start":"2026-03-19T17:46:49.388683Z","end":"2026-03-19T17:46:49.836334Z","steps":["trace[543380730] 'process raft request' (duration: 447.254406ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.83642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.570678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5296"} {"level":"info","ts":"2026-03-19T17:46:49.836438Z","caller":"traceutil/trace.go:171","msg":"trace[1254284469] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"443.781576ms","start":"2026-03-19T17:46:49.392643Z","end":"2026-03-19T17:46:49.836425Z","steps":["trace[1254284469] 'process raft request' (duration: 443.465196ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.836461Z","caller":"traceutil/trace.go:171","msg":"trace[783548111] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:386; }","duration":"448.68167ms","start":"2026-03-19T17:46:49.38777Z","end":"2026-03-19T17:46:49.836451Z","steps":["trace[783548111] 'agreement among raft nodes before linearized reading' (duration: 448.551416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.836489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:49.392624Z","time spent":"443.841078ms","remote":"127.0.0.1:51442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:46:49.836493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:49.387763Z","time spent":"448.723302ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5319,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-03-19T17:46:49.836499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:49.392577Z","time spent":"443.838737ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4781,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:49.836578Z","caller":"traceutil/trace.go:171","msg":"trace[542712749] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"450.965323ms","start":"2026-03-19T17:46:49.385601Z","end":"2026-03-19T17:46:49.836566Z","steps":["trace[542712749] 'process raft request' (duration: 450.055974ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.836394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:49.393791Z","time spent":"442.567697ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4251,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:49.836616Z","caller":"traceutil/trace.go:171","msg":"trace[1482795588] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"446.797591ms","start":"2026-03-19T17:46:49.389788Z","end":"2026-03-19T17:46:49.836585Z","steps":["trace[1482795588] 'process raft request' (duration: 446.186002ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.836503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:49.388676Z","time spent":"447.762761ms","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:46:49.836737Z","caller":"traceutil/trace.go:171","msg":"trace[470519545] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"440.849483ms","start":"2026-03-19T17:46:49.395875Z","end":"2026-03-19T17:46:49.836724Z","steps":["trace[470519545] 'process raft request' (duration: 440.349477ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.836759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:49.389781Z","time spent":"446.905084ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:46:49.83628Z","caller":"traceutil/trace.go:171","msg":"trace[1687769436] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:388; }","duration":"448.468434ms","start":"2026-03-19T17:46:49.387795Z","end":"2026-03-19T17:46:49.836263Z","steps":["trace[1687769436] 'read index received' (duration: 63.492758ms)","trace[1687769436] 'applied index is now lower than readState.Index' (duration: 384.974706ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:49.836798Z","caller":"traceutil/trace.go:171","msg":"trace[1757036977] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"449.966051ms","start":"2026-03-19T17:46:49.386819Z","end":"2026-03-19T17:46:49.836785Z","steps":["trace[1757036977] 'process raft request' (duration: 448.949319ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.836838Z","caller":"traceutil/trace.go:171","msg":"trace[1745015202] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"441.211585ms","start":"2026-03-19T17:46:49.395617Z","end":"2026-03-19T17:46:49.836829Z","steps":["trace[1745015202] 'process raft request' (duration: 440.574684ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.836646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:49.38559Z","time spent":"451.016335ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:46:49.836863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:49.386809Z","time spent":"450.018794ms","remote":"127.0.0.1:51442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:46:49.836883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:49.395608Z","time spent":"441.250865ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:46:49.836417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:49.386931Z","time spent":"449.409354ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T17:46:49.8368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:49.395861Z","time spent":"440.904814ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6872,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:49.8421Z","caller":"traceutil/trace.go:171","msg":"trace[1123470904] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"386.0518ms","start":"2026-03-19T17:46:49.456034Z","end":"2026-03-19T17:46:49.842086Z","steps":["trace[1123470904] 'process raft request' (duration: 385.963097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.842178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:49.456017Z","time spent":"386.127431ms","remote":"127.0.0.1:51250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:46:49.976047Z","caller":"traceutil/trace.go:171","msg":"trace[1808501198] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"131.300272ms","start":"2026-03-19T17:46:49.844726Z","end":"2026-03-19T17:46:49.976026Z","steps":["trace[1808501198] 'process raft request' (duration: 131.213671ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.976047Z","caller":"traceutil/trace.go:171","msg":"trace[92765384] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"131.380975ms","start":"2026-03-19T17:46:49.844628Z","end":"2026-03-19T17:46:49.976009Z","steps":["trace[92765384] 'process raft request' (duration: 115.586546ms)","trace[92765384] 'compare' (duration: 15.536361ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:49.976171Z","caller":"traceutil/trace.go:171","msg":"trace[264010511] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"131.175548ms","start":"2026-03-19T17:46:49.84497Z","end":"2026-03-19T17:46:49.976146Z","steps":["trace[264010511] 'process raft request' (duration: 131.005563ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.976328Z","caller":"traceutil/trace.go:171","msg":"trace[58648952] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"130.29318ms","start":"2026-03-19T17:46:49.845992Z","end":"2026-03-19T17:46:49.976285Z","steps":["trace[58648952] 'process raft request' (duration: 130.016451ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.976399Z","caller":"traceutil/trace.go:171","msg":"trace[553562491] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"130.23204ms","start":"2026-03-19T17:46:49.846157Z","end":"2026-03-19T17:46:49.976389Z","steps":["trace[553562491] 'process raft request' (duration: 129.884479ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.976364Z","caller":"traceutil/trace.go:171","msg":"trace[235755078] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"128.450452ms","start":"2026-03-19T17:46:49.847883Z","end":"2026-03-19T17:46:49.976333Z","steps":["trace[235755078] 'process raft request' (duration: 128.215315ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.976405Z","caller":"traceutil/trace.go:171","msg":"trace[2089435429] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"131.663305ms","start":"2026-03-19T17:46:49.844724Z","end":"2026-03-19T17:46:49.976387Z","steps":["trace[2089435429] 'process raft request' (duration: 131.163798ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.976577Z","caller":"traceutil/trace.go:171","msg":"trace[188916918] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"128.210205ms","start":"2026-03-19T17:46:49.848357Z","end":"2026-03-19T17:46:49.976567Z","steps":["trace[188916918] 'process raft request' (duration: 127.884214ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.976559Z","caller":"traceutil/trace.go:171","msg":"trace[872000104] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"114.342335ms","start":"2026-03-19T17:46:49.862211Z","end":"2026-03-19T17:46:49.976553Z","steps":["trace[872000104] 'process raft request' (duration: 114.282744ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:50.390766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.532388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:50.391093Z","caller":"traceutil/trace.go:171","msg":"trace[1086949103] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"349.113032ms","start":"2026-03-19T17:46:50.041952Z","end":"2026-03-19T17:46:50.391065Z","steps":["trace[1086949103] 'process raft request' (duration: 88.179271ms)","trace[1086949103] 'compare' (duration: 260.406634ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:50.391124Z","caller":"traceutil/trace.go:171","msg":"trace[2092418692] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:421; }","duration":"348.660439ms","start":"2026-03-19T17:46:50.042442Z","end":"2026-03-19T17:46:50.391102Z","steps":["trace[2092418692] 'read index received' (duration: 171.996µs)","trace[2092418692] 'applied index is now lower than readState.Index' (duration: 348.487073ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:50.3911Z","caller":"traceutil/trace.go:171","msg":"trace[2106606265] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"348.690599ms","start":"2026-03-19T17:46:50.04235Z","end":"2026-03-19T17:46:50.391041Z","steps":["trace[2106606265] 'process raft request' (duration: 348.527914ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:50.391259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.510539ms","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-03-19T17:46:50.391295Z","caller":"traceutil/trace.go:171","msg":"trace[670165948] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:411; }","duration":"156.588942ms","start":"2026-03-19T17:46:50.234696Z","end":"2026-03-19T17:46:50.391285Z","steps":["trace[670165948] 'agreement among raft nodes before linearized reading' (duration: 156.51863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:50.39135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:50.042326Z","time spent":"348.890926ms","remote":"127.0.0.1:51610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:46:50.391371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:50.041945Z","time spent":"349.218886ms","remote":"127.0.0.1:51598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:46:50.391488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.969177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-scheduler-instance.189e4f38fb5192cf\" ","response":"range_response_count:1 size:687"} {"level":"info","ts":"2026-03-19T17:46:50.391903Z","caller":"traceutil/trace.go:171","msg":"trace[682782123] range","detail":"{range_begin:/registry/events/kube-system/kube-scheduler-instance.189e4f38fb5192cf; range_end:; response_count:1; response_revision:411; }","duration":"349.455143ms","start":"2026-03-19T17:46:50.042429Z","end":"2026-03-19T17:46:50.391884Z","steps":["trace[682782123] 'agreement among raft nodes before linearized reading' (duration: 348.74925ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:50.391955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:50.042424Z","time spent":"349.519565ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":710,"request content":"key:\"/registry/events/kube-system/kube-scheduler-instance.189e4f38fb5192cf\" "} {"level":"info","ts":"2026-03-19T17:46:50.391655Z","caller":"traceutil/trace.go:171","msg":"trace[1292727738] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"344.108326ms","start":"2026-03-19T17:46:50.047533Z","end":"2026-03-19T17:46:50.391641Z","steps":["trace[1292727738] 'process raft request' (duration: 343.440924ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:50.392275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:50.047526Z","time spent":"344.691593ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:50.654395Z","caller":"traceutil/trace.go:171","msg":"trace[521375092] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"254.344954ms","start":"2026-03-19T17:46:50.400018Z","end":"2026-03-19T17:46:50.654363Z","steps":["trace[521375092] 'process raft request' (duration: 160.001579ms)","trace[521375092] 'compare' (duration: 94.221501ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:50.656736Z","caller":"traceutil/trace.go:171","msg":"trace[214818466] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:426; }","duration":"118.410402ms","start":"2026-03-19T17:46:50.538308Z","end":"2026-03-19T17:46:50.656718Z","steps":["trace[214818466] 'read index received' (duration: 21.708443ms)","trace[214818466] 'applied index is now lower than readState.Index' (duration: 96.700489ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:50.656857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.538975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.177\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-19T17:46:50.656845Z","caller":"traceutil/trace.go:171","msg":"trace[2021382866] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"211.179477ms","start":"2026-03-19T17:46:50.445631Z","end":"2026-03-19T17:46:50.656811Z","steps":["trace[2021382866] 'process raft request' (duration: 211.019511ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:50.656902Z","caller":"traceutil/trace.go:171","msg":"trace[182159877] range","detail":"{range_begin:/registry/masterleases/199.19.213.177; range_end:; response_count:1; response_revision:415; }","duration":"118.670299ms","start":"2026-03-19T17:46:50.538218Z","end":"2026-03-19T17:46:50.656889Z","steps":["trace[182159877] 'agreement among raft nodes before linearized reading' (duration: 118.567756ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:50.656901Z","caller":"traceutil/trace.go:171","msg":"trace[416222554] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"215.999007ms","start":"2026-03-19T17:46:50.440874Z","end":"2026-03-19T17:46:50.656873Z","steps":["trace[416222554] 'process raft request' (duration: 215.689958ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:52.215554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.031695ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:52.215637Z","caller":"traceutil/trace.go:171","msg":"trace[2006535946] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"369.075776ms","start":"2026-03-19T17:46:51.846549Z","end":"2026-03-19T17:46:52.215624Z","steps":["trace[2006535946] 'process raft request' (duration: 104.911327ms)","trace[2006535946] 'compare' (duration: 263.954823ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:52.215694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:51.846527Z","time spent":"369.134007ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:52.452824Z","caller":"traceutil/trace.go:171","msg":"trace[302424023] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:437; }","duration":"268.600717ms","start":"2026-03-19T17:46:52.184197Z","end":"2026-03-19T17:46:52.452798Z","steps":["trace[302424023] 'read index received' (duration: 226.069797ms)","trace[302424023] 'applied index is now lower than readState.Index' (duration: 42.53017ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:52.452845Z","caller":"traceutil/trace.go:171","msg":"trace[2050540041] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"364.967418ms","start":"2026-03-19T17:46:52.087839Z","end":"2026-03-19T17:46:52.452806Z","steps":["trace[2050540041] 'process raft request' (duration: 322.449859ms)","trace[2050540041] 'compare' (duration: 42.349784ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:52.453007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:52.087818Z","time spent":"365.103253ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T17:46:52.453007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.790654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-03-19T17:46:52.453168Z","caller":"traceutil/trace.go:171","msg":"trace[1144450714] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:423; }","duration":"269.0068ms","start":"2026-03-19T17:46:52.184146Z","end":"2026-03-19T17:46:52.453152Z","steps":["trace[1144450714] 'agreement among raft nodes before linearized reading' (duration: 268.744501ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:53.893388Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7053091145714552211,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:46:53.98007Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.269688576s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T17:46:53.980476Z","caller":"traceutil/trace.go:171","msg":"trace[1408279735] linearizableReadLoop","detail":"{readStateIndex:442; appliedIndex:441; }","duration":"587.194075ms","start":"2026-03-19T17:46:53.393257Z","end":"2026-03-19T17:46:53.980451Z","steps":["trace[1408279735] 'read index received' (duration: 586.959968ms)","trace[1408279735] 'applied index is now lower than readState.Index' (duration: 233.117µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:53.980534Z","caller":"traceutil/trace.go:171","msg":"trace[2010812857] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"1.270268624s","start":"2026-03-19T17:46:52.710232Z","end":"2026-03-19T17:46:53.980501Z","steps":["trace[2010812857] 'process raft request' (duration: 1.270065597s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:53.980634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.3607ms","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-03-19T17:46:53.98067Z","caller":"traceutil/trace.go:171","msg":"trace[506799374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:426; }","duration":"587.447452ms","start":"2026-03-19T17:46:53.393213Z","end":"2026-03-19T17:46:53.98066Z","steps":["trace[506799374] 'agreement among raft nodes before linearized reading' (duration: 587.355759ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:53.980714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:53.39319Z","time spent":"587.516376ms","remote":"127.0.0.1:51360","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-03-19T17:46:53.98081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:52.710185Z","time spent":"1.27047661s","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:46:53.981084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.859421ms","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-03-19T17:46:53.981249Z","caller":"traceutil/trace.go:171","msg":"trace[745504894] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:426; }","duration":"583.095428ms","start":"2026-03-19T17:46:53.398124Z","end":"2026-03-19T17:46:53.981219Z","steps":["trace[745504894] 'agreement among raft nodes before linearized reading' (duration: 582.781479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:53.981348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:53.398099Z","time spent":"583.235024ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T17:46:54.486184Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7053091145714552214,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:46:54.986344Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7053091145714552214,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:46:55.487293Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7053091145714552214,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:46:55.987758Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7053091145714552214,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:46:56.488074Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7053091145714552214,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:46:56.988514Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7053091145714552214,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:46:57.355081Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.286360761s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T17:46:57.452068Z","caller":"traceutil/trace.go:171","msg":"trace[2115899961] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"3.466904538s","start":"2026-03-19T17:46:53.985138Z","end":"2026-03-19T17:46:57.452043Z","steps":["trace[2115899961] 'process raft request' (duration: 3.370068071s)","trace[2115899961] 'compare' (duration: 96.133616ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:57.452197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:53.985113Z","time spent":"3.467016561s","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:57.495118Z","caller":"traceutil/trace.go:171","msg":"trace[2030954804] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"3.508407388s","start":"2026-03-19T17:46:53.986687Z","end":"2026-03-19T17:46:57.495095Z","steps":["trace[2030954804] 'process raft request' (duration: 3.508228853s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:57.495262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:53.986678Z","time spent":"3.508507841s","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:57.495488Z","caller":"traceutil/trace.go:171","msg":"trace[1623009743] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"3.507807399s","start":"2026-03-19T17:46:53.987649Z","end":"2026-03-19T17:46:57.495456Z","steps":["trace[1623009743] 'process raft request' (duration: 3.507402967s)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:57.495703Z","caller":"traceutil/trace.go:171","msg":"trace[1070456106] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"2.746506912s","start":"2026-03-19T17:46:54.749184Z","end":"2026-03-19T17:46:57.495691Z","steps":["trace[1070456106] 'process raft request' (duration: 2.746173432s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:57.495693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:53.987638Z","time spent":"3.507962583s","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:46:57.495771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:54.749155Z","time spent":"2.746579833s","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:46:57.732643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.959867ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-19T17:46:57.732884Z","caller":"traceutil/trace.go:171","msg":"trace[874237126] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:442; }","duration":"3.747449959s","start":"2026-03-19T17:46:53.985415Z","end":"2026-03-19T17:46:57.732864Z","steps":["trace[874237126] 'read index received' (duration: 3.370007589s)","trace[874237126] 'applied index is now lower than readState.Index' (duration: 377.44145ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:57.733085Z","caller":"traceutil/trace.go:171","msg":"trace[655695507] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"555.334588ms","start":"2026-03-19T17:46:57.177708Z","end":"2026-03-19T17:46:57.733043Z","steps":["trace[655695507] 'process raft request' (duration: 555.030708ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:57.733285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.747854021s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-d99s2\" ","response":"range_response_count:1 size:4466"} {"level":"info","ts":"2026-03-19T17:46:57.733402Z","caller":"traceutil/trace.go:171","msg":"trace[861002658] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-d99s2; range_end:; response_count:1; response_revision:431; }","duration":"3.748003776s","start":"2026-03-19T17:46:53.98538Z","end":"2026-03-19T17:46:57.733384Z","steps":["trace[861002658] 'agreement among raft nodes before linearized reading' (duration: 3.747749118s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:57.733225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.991324412s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:57.733559Z","caller":"traceutil/trace.go:171","msg":"trace[173611707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:431; }","duration":"1.991697473s","start":"2026-03-19T17:46:55.741836Z","end":"2026-03-19T17:46:57.733534Z","steps":["trace[173611707] 'agreement among raft nodes before linearized reading' (duration: 1.991332192s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:57.733244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.513181001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-19T17:46:57.733647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:55.741803Z","time spent":"1.991828977s","remote":"127.0.0.1:51126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T17:46:57.73352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:53.985371Z","time spent":"3.748133179s","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":4489,"request content":"key:\"/registry/pods/kube-system/kube-proxy-d99s2\" "} {"level":"info","ts":"2026-03-19T17:46:57.733707Z","caller":"traceutil/trace.go:171","msg":"trace[2019160471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:431; }","duration":"3.513671276s","start":"2026-03-19T17:46:54.219989Z","end":"2026-03-19T17:46:57.73366Z","steps":["trace[2019160471] 'agreement among raft nodes before linearized reading' (duration: 3.51313766s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:57.733855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:54.219971Z","time spent":"3.513840642s","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T17:46:57.734194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:57.177692Z","time spent":"555.529614ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:57.902645Z","caller":"traceutil/trace.go:171","msg":"trace[237614189] linearizableReadLoop","detail":"{readStateIndex:450; appliedIndex:449; }","duration":"121.215142ms","start":"2026-03-19T17:46:57.781407Z","end":"2026-03-19T17:46:57.902622Z","steps":["trace[237614189] 'read index received' (duration: 121.091307ms)","trace[237614189] 'applied index is now lower than readState.Index' (duration: 122.814µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:57.90275Z","caller":"traceutil/trace.go:171","msg":"trace[803094158] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"159.494675ms","start":"2026-03-19T17:46:57.743216Z","end":"2026-03-19T17:46:57.902711Z","steps":["trace[803094158] 'process raft request' (duration: 159.288508ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:57.90293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.51173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:57.903002Z","caller":"traceutil/trace.go:171","msg":"trace[1915286265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:433; }","duration":"121.627514ms","start":"2026-03-19T17:46:57.781361Z","end":"2026-03-19T17:46:57.902989Z","steps":["trace[1915286265] 'agreement among raft nodes before linearized reading' (duration: 121.349475ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:58.076337Z","caller":"traceutil/trace.go:171","msg":"trace[378590372] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"162.030127ms","start":"2026-03-19T17:46:57.914282Z","end":"2026-03-19T17:46:58.076312Z","steps":["trace[378590372] 'process raft request' (duration: 160.015967ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:58.510538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.375228ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:58.510652Z","caller":"traceutil/trace.go:171","msg":"trace[660136080] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"392.661849ms","start":"2026-03-19T17:46:58.117971Z","end":"2026-03-19T17:46:58.510633Z","steps":["trace[660136080] 'process raft request' (duration: 145.106606ms)","trace[660136080] 'compare' (duration: 247.233133ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:58.5108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:58.117959Z","time spent":"392.732371ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4649,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:58.803869Z","caller":"traceutil/trace.go:171","msg":"trace[1517787553] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"282.037945ms","start":"2026-03-19T17:46:58.521768Z","end":"2026-03-19T17:46:58.803806Z","steps":["trace[1517787553] 'process raft request' (duration: 187.480914ms)","trace[1517787553] 'compare' (duration: 94.438238ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:59.967135Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7053091145714552236,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T17:47:00.284213Z","caller":"traceutil/trace.go:171","msg":"trace[827707116] linearizableReadLoop","detail":"{readStateIndex:456; appliedIndex:455; }","duration":"817.501611ms","start":"2026-03-19T17:46:59.466685Z","end":"2026-03-19T17:47:00.284187Z","steps":["trace[827707116] 'read index received' (duration: 817.211473ms)","trace[827707116] 'applied index is now lower than readState.Index' (duration: 289.038µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:47:00.284401Z","caller":"traceutil/trace.go:171","msg":"trace[697638756] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"822.83695ms","start":"2026-03-19T17:46:59.461526Z","end":"2026-03-19T17:47:00.284363Z","steps":["trace[697638756] 'process raft request' (duration: 822.473709ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:00.284702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.857549ms","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-03-19T17:47:00.284755Z","caller":"traceutil/trace.go:171","msg":"trace[2028713677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:439; }","duration":"785.944993ms","start":"2026-03-19T17:46:59.498799Z","end":"2026-03-19T17:47:00.284744Z","steps":["trace[2028713677] 'agreement among raft nodes before linearized reading' (duration: 785.836019ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:00.284788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:59.498789Z","time spent":"785.991513ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T17:47:00.284799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:59.4615Z","time spent":"823.12424ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:47:00.284486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.77686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T17:47:00.285022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.274032ms","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-03-19T17:47:00.285055Z","caller":"traceutil/trace.go:171","msg":"trace[114935726] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:439; }","duration":"784.355405ms","start":"2026-03-19T17:46:59.500691Z","end":"2026-03-19T17:47:00.285047Z","steps":["trace[114935726] 'agreement among raft nodes before linearized reading' (duration: 784.309584ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:00.285086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:59.500678Z","time spent":"784.402507ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-19T17:47:00.285021Z","caller":"traceutil/trace.go:171","msg":"trace[201311824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:439; }","duration":"818.347527ms","start":"2026-03-19T17:46:59.46666Z","end":"2026-03-19T17:47:00.285007Z","steps":["trace[201311824] 'agreement among raft nodes before linearized reading' (duration: 817.726178ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:00.285259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:59.466652Z","time spent":"818.594784ms","remote":"127.0.0.1:51126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T17:47:00.284983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.759661ms","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-03-19T17:47:00.285299Z","caller":"traceutil/trace.go:171","msg":"trace[1131721047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:439; }","duration":"501.109532ms","start":"2026-03-19T17:46:59.784179Z","end":"2026-03-19T17:47:00.285289Z","steps":["trace[1131721047] 'agreement among raft nodes before linearized reading' (duration: 500.775592ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:00.285339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:59.784157Z","time spent":"501.172665ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T17:47:02.331837Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.499332828s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-19T17:47:02.468758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000376396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-19T17:47:02.468905Z","caller":"traceutil/trace.go:171","msg":"trace[553214226] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000537821s","start":"2026-03-19T17:47:00.468346Z","end":"2026-03-19T17:47:02.468884Z","steps":["trace[553214226] 'agreement among raft nodes before linearized reading' (duration: 2.000373186s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:02.468957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:00.468297Z","time spent":"2.000650404s","remote":"127.0.0.1:51126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/19 17:47:02 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-19T17:47:03.417076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.984184977s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:47:03.417211Z","caller":"traceutil/trace.go:171","msg":"trace[872578310] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:439; }","duration":"1.984342372s","start":"2026-03-19T17:47:01.43285Z","end":"2026-03-19T17:47:03.417193Z","steps":["trace[872578310] 'range keys from in-memory index tree' (duration: 1.984165547s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:03.417876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.585381652s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:47:03.418142Z","caller":"traceutil/trace.go:171","msg":"trace[468237080] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"3.128688716s","start":"2026-03-19T17:47:00.28942Z","end":"2026-03-19T17:47:03.418108Z","steps":["trace[468237080] 'process raft request' (duration: 542.971835ms)","trace[468237080] 'compare' (duration: 2.584901968s)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:47:03.418183Z","caller":"traceutil/trace.go:171","msg":"trace[764273028] linearizableReadLoop","detail":"{readStateIndex:459; appliedIndex:456; }","duration":"2.94980397s","start":"2026-03-19T17:47:00.468362Z","end":"2026-03-19T17:47:03.418166Z","steps":["trace[764273028] 'read index received' (duration: 364.13728ms)","trace[764273028] 'applied index is now lower than readState.Index' (duration: 2.58566517s)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:47:03.418269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:00.289401Z","time spent":"3.128811599s","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:47:03.418293Z","caller":"traceutil/trace.go:171","msg":"trace[1668859194] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"3.128330375s","start":"2026-03-19T17:47:00.289945Z","end":"2026-03-19T17:47:03.418275Z","steps":["trace[1668859194] 'process raft request' (duration: 3.1281696s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:03.418371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.880231681s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.177\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-19T17:47:03.418365Z","caller":"traceutil/trace.go:171","msg":"trace[901025003] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"3.128866692s","start":"2026-03-19T17:47:00.289469Z","end":"2026-03-19T17:47:03.418336Z","steps":["trace[901025003] 'process raft request' (duration: 3.128528881s)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:03.418424Z","caller":"traceutil/trace.go:171","msg":"trace[1483407599] range","detail":"{range_begin:/registry/masterleases/199.19.213.177; range_end:; response_count:1; response_revision:442; }","duration":"2.880290292s","start":"2026-03-19T17:47:00.538121Z","end":"2026-03-19T17:47:03.418411Z","steps":["trace[1483407599] 'agreement among raft nodes before linearized reading' (duration: 2.880177579s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:03.418413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12861302s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-19T17:47:03.418471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:00.538057Z","time spent":"2.880400286s","remote":"127.0.0.1:51168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.177\" "} {"level":"info","ts":"2026-03-19T17:47:03.418483Z","caller":"traceutil/trace.go:171","msg":"trace[703077648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:442; }","duration":"1.128714333s","start":"2026-03-19T17:47:02.289755Z","end":"2026-03-19T17:47:03.418469Z","steps":["trace[703077648] 'agreement among raft nodes before linearized reading' (duration: 1.12862343s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:03.418527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:02.289734Z","time spent":"1.128782864s","remote":"127.0.0.1:51360","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-03-19T17:47:03.418535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:00.289448Z","time spent":"3.128984325s","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:47:03.418544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:00.289934Z","time spent":"3.128455879s","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:47:03.418338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.067103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:47:03.418833Z","caller":"traceutil/trace.go:171","msg":"trace[76539091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:442; }","duration":"949.568708ms","start":"2026-03-19T17:47:02.469225Z","end":"2026-03-19T17:47:03.418794Z","steps":["trace[76539091] 'agreement among raft nodes before linearized reading' (duration: 949.071623ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:03.419318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:02.46921Z","time spent":"950.089014ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T17:47:03.700391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.447778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:47:03.700652Z","caller":"traceutil/trace.go:171","msg":"trace[1152626183] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"191.395901ms","start":"2026-03-19T17:47:03.509238Z","end":"2026-03-19T17:47:03.700634Z","steps":["trace[1152626183] 'process raft request' (duration: 191.342069ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:03.700633Z","caller":"traceutil/trace.go:171","msg":"trace[2080431071] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"274.790639ms","start":"2026-03-19T17:47:03.425814Z","end":"2026-03-19T17:47:03.700604Z","steps":["trace[2080431071] 'process raft request' (duration: 134.049812ms)","trace[2080431071] 'compare' (duration: 140.278203ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:47:03.70078Z","caller":"traceutil/trace.go:171","msg":"trace[376390956] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"272.282065ms","start":"2026-03-19T17:47:03.428474Z","end":"2026-03-19T17:47:03.700756Z","steps":["trace[376390956] 'process raft request' (duration: 272.012867ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:03.700932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.9555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:47:03.70097Z","caller":"traceutil/trace.go:171","msg":"trace[2046766501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:445; }","duration":"267.994961ms","start":"2026-03-19T17:47:03.432966Z","end":"2026-03-19T17:47:03.700961Z","steps":["trace[2046766501] 'agreement among raft nodes before linearized reading' (duration: 267.903499ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:03.700849Z","caller":"traceutil/trace.go:171","msg":"trace[893580243] linearizableReadLoop","detail":"{readStateIndex:462; appliedIndex:459; }","duration":"267.867078ms","start":"2026-03-19T17:47:03.432971Z","end":"2026-03-19T17:47:03.700838Z","steps":["trace[893580243] 'read index received' (duration: 74.978793ms)","trace[893580243] 'applied index is now lower than readState.Index' (duration: 192.887125ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:47:05.951007Z","caller":"traceutil/trace.go:171","msg":"trace[624513367] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"243.279275ms","start":"2026-03-19T17:47:05.707699Z","end":"2026-03-19T17:47:05.950978Z","steps":["trace[624513367] 'process raft request' (duration: 243.071889ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:06.158591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.402315ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-03-19T17:47:11.254859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.566269ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:47:11.254978Z","caller":"traceutil/trace.go:171","msg":"trace[1198442572] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"545.760938ms","start":"2026-03-19T17:47:10.709199Z","end":"2026-03-19T17:47:11.25496Z","steps":["trace[1198442572] 'process raft request' (duration: 269.007734ms)","trace[1198442572] 'compare' (duration: 276.344163ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:47:11.255042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:10.709173Z","time spent":"545.84032ms","remote":"127.0.0.1:51168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:47:11.69206Z","caller":"traceutil/trace.go:171","msg":"trace[1418056428] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"157.146524ms","start":"2026-03-19T17:47:11.53489Z","end":"2026-03-19T17:47:11.692036Z","steps":["trace[1418056428] 'process raft request' (duration: 125.937764ms)","trace[1418056428] 'compare' (duration: 30.985944ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:47:11.869065Z","caller":"traceutil/trace.go:171","msg":"trace[1209028459] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"333.278579ms","start":"2026-03-19T17:47:11.535323Z","end":"2026-03-19T17:47:11.868602Z","steps":["trace[1209028459] 'process raft request' (duration: 332.051435ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:11.869346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:11.535314Z","time spent":"333.935167ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:47:12.072733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.310854ms","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-03-19T17:47:12.072941Z","caller":"traceutil/trace.go:171","msg":"trace[746619019] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:463; }","duration":"102.50089ms","start":"2026-03-19T17:47:11.970361Z","end":"2026-03-19T17:47:12.072862Z","steps":["trace[746619019] 'range keys from in-memory index tree' (duration: 102.231562ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:12.289314Z","caller":"traceutil/trace.go:171","msg":"trace[1023415624] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"135.042709ms","start":"2026-03-19T17:47:12.154195Z","end":"2026-03-19T17:47:12.289238Z","steps":["trace[1023415624] 'process raft request' (duration: 134.881475ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:28.452707Z","caller":"traceutil/trace.go:171","msg":"trace[2009098585] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"254.76026ms","start":"2026-03-19T17:47:28.197928Z","end":"2026-03-19T17:47:28.452688Z","steps":["trace[2009098585] 'process raft request' (duration: 254.659707ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:28.457929Z","caller":"traceutil/trace.go:171","msg":"trace[1143970150] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"129.655442ms","start":"2026-03-19T17:47:28.328252Z","end":"2026-03-19T17:47:28.457908Z","steps":["trace[1143970150] 'process raft request' (duration: 129.55285ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:29.658815Z","caller":"traceutil/trace.go:171","msg":"trace[1196271278] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"952.714827ms","start":"2026-03-19T17:47:28.706076Z","end":"2026-03-19T17:47:29.658791Z","steps":["trace[1196271278] 'process raft request' (duration: 952.541904ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:29.658974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:28.706048Z","time spent":"952.85048ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:47:31.835886Z","caller":"traceutil/trace.go:171","msg":"trace[782598890] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"101.107964ms","start":"2026-03-19T17:47:31.734757Z","end":"2026-03-19T17:47:31.835865Z","steps":["trace[782598890] 'process raft request' (duration: 100.755665ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:32.084255Z","caller":"traceutil/trace.go:171","msg":"trace[1602842829] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"112.676082ms","start":"2026-03-19T17:47:31.971552Z","end":"2026-03-19T17:47:32.084228Z","steps":["trace[1602842829] 'read index received' (duration: 111.82568ms)","trace[1602842829] 'applied index is now lower than readState.Index' (duration: 849.112µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:47:32.084361Z","caller":"traceutil/trace.go:171","msg":"trace[1626602315] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"244.18506ms","start":"2026-03-19T17:47:31.840122Z","end":"2026-03-19T17:47:32.084307Z","steps":["trace[1626602315] 'process raft request' (duration: 243.193745ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:32.084503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.926318ms","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-03-19T17:47:32.084542Z","caller":"traceutil/trace.go:171","msg":"trace[1239320198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:574; }","duration":"113.04045ms","start":"2026-03-19T17:47:31.971489Z","end":"2026-03-19T17:47:32.08453Z","steps":["trace[1239320198] 'agreement among raft nodes before linearized reading' (duration: 112.854226ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:36.071499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.560543ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-19T17:47:36.071609Z","caller":"traceutil/trace.go:171","msg":"trace[1706208941] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"222.471089ms","start":"2026-03-19T17:47:35.849114Z","end":"2026-03-19T17:47:36.071585Z","steps":["trace[1706208941] 'read index received' (duration: 43.581µs)","trace[1706208941] 'applied index is now lower than readState.Index' (duration: 222.425618ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:47:36.071764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.715776ms","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-03-19T17:47:36.071793Z","caller":"traceutil/trace.go:171","msg":"trace[487678489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:606; }","duration":"222.788679ms","start":"2026-03-19T17:47:35.848994Z","end":"2026-03-19T17:47:36.071782Z","steps":["trace[487678489] 'agreement among raft nodes before linearized reading' (duration: 222.644975ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:36.755899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.98556ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:47:36.756071Z","caller":"traceutil/trace.go:171","msg":"trace[1204440461] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"601.607284ms","start":"2026-03-19T17:47:36.154441Z","end":"2026-03-19T17:47:36.756049Z","steps":["trace[1204440461] 'read index received' (duration: 261.305066ms)","trace[1204440461] 'applied index is now lower than readState.Index' (duration: 340.300498ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:47:36.7564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.939422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-19T17:47:36.756291Z","caller":"traceutil/trace.go:171","msg":"trace[88627097] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"623.86709ms","start":"2026-03-19T17:47:36.132225Z","end":"2026-03-19T17:47:36.756092Z","steps":["trace[88627097] 'process raft request' (duration: 283.548851ms)","trace[88627097] 'compare' (duration: 339.718484ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:47:36.756456Z","caller":"traceutil/trace.go:171","msg":"trace[469326733] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:608; }","duration":"602.026485ms","start":"2026-03-19T17:47:36.154414Z","end":"2026-03-19T17:47:36.756441Z","steps":["trace[469326733] 'agreement among raft nodes before linearized reading' (duration: 601.800669ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:36.756511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:36.154396Z","time spent":"602.103856ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T17:47:36.756733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:36.132202Z","time spent":"624.278491ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:47:36.75816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.114335ms","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-03-19T17:47:36.758278Z","caller":"traceutil/trace.go:171","msg":"trace[586999060] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:608; }","duration":"274.266958ms","start":"2026-03-19T17:47:36.483988Z","end":"2026-03-19T17:47:36.758255Z","steps":["trace[586999060] 'agreement among raft nodes before linearized reading' (duration: 274.007322ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:37.027907Z","caller":"traceutil/trace.go:171","msg":"trace[1761392883] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"265.762216ms","start":"2026-03-19T17:47:36.762119Z","end":"2026-03-19T17:47:37.027881Z","steps":["trace[1761392883] 'process raft request' (duration: 265.587781ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:37.051683Z","caller":"traceutil/trace.go:171","msg":"trace[1072670523] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"289.22309ms","start":"2026-03-19T17:47:36.762432Z","end":"2026-03-19T17:47:37.051656Z","steps":["trace[1072670523] 'process raft request' (duration: 289.101997ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:39.261758Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7053091145714552606,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:47:39.763366Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7053091145714552606,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:47:40.263745Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7053091145714552606,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:47:40.691383Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.017492215s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-19T17:47:40.760239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.260549506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-19T17:47:40.760326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.293777733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:47:40.760384Z","caller":"traceutil/trace.go:171","msg":"trace[1786521292] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:610; }","duration":"2.26070918s","start":"2026-03-19T17:47:38.499615Z","end":"2026-03-19T17:47:40.760324Z","steps":["trace[1786521292] 'count revisions from in-memory index tree' (duration: 2.260460762s)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:40.760417Z","caller":"traceutil/trace.go:171","msg":"trace[1684320046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"3.293920127s","start":"2026-03-19T17:47:37.466474Z","end":"2026-03-19T17:47:40.760394Z","steps":["trace[1684320046] 'range keys from in-memory index tree' (duration: 3.293704962s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:40.76044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:38.499596Z","time spent":"2.260830852s","remote":"127.0.0.1:51304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-03-19T17:47:40.760466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:37.466455Z","time spent":"3.29400196s","remote":"127.0.0.1:51126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T17:47:40.760487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.627063019s","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-03-19T17:47:40.760544Z","caller":"traceutil/trace.go:171","msg":"trace[1144888340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"2.627183502s","start":"2026-03-19T17:47:38.133347Z","end":"2026-03-19T17:47:40.760531Z","steps":["trace[1144888340] 'range keys from in-memory index tree' (duration: 2.626888805s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:40.760572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:38.133327Z","time spent":"2.627239692s","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-19T17:47:40.760762Z","caller":"traceutil/trace.go:171","msg":"trace[273042755] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"2.086955763s","start":"2026-03-19T17:47:38.673786Z","end":"2026-03-19T17:47:40.760742Z","steps":["trace[273042755] 'process raft request' (duration: 2.018042778s)","trace[273042755] 'compare' (duration: 68.755931ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:47:40.760876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:38.673764Z","time spent":"2.087042894s","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:47:40.880417Z","caller":"traceutil/trace.go:171","msg":"trace[2087669897] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"2.119026185s","start":"2026-03-19T17:47:38.761351Z","end":"2026-03-19T17:47:40.880377Z","steps":["trace[2087669897] 'read index received' (duration: 1.930432106s)","trace[2087669897] 'applied index is now lower than readState.Index' (duration: 188.592429ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:47:40.880576Z","caller":"traceutil/trace.go:171","msg":"trace[889438478] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"1.145396993s","start":"2026-03-19T17:47:39.735133Z","end":"2026-03-19T17:47:40.88053Z","steps":["trace[889438478] 'process raft request' (duration: 1.145036554s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:40.880818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.119459196s","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-03-19T17:47:40.880883Z","caller":"traceutil/trace.go:171","msg":"trace[1377294024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:612; }","duration":"2.119585129s","start":"2026-03-19T17:47:38.761281Z","end":"2026-03-19T17:47:40.880866Z","steps":["trace[1377294024] 'agreement among raft nodes before linearized reading' (duration: 2.119351273s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:40.880934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:38.761246Z","time spent":"2.119677043s","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T17:47:40.880971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:39.73511Z","time spent":"1.14570379s","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:47:41.10473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.27941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:47:41.104909Z","caller":"traceutil/trace.go:171","msg":"trace[1119883547] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"224.374611ms","start":"2026-03-19T17:47:40.880517Z","end":"2026-03-19T17:47:41.104891Z","steps":["trace[1119883547] 'read index received' (duration: 101.855967ms)","trace[1119883547] 'applied index is now lower than readState.Index' (duration: 122.517504ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:47:41.104979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.078670109s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:47:41.105008Z","caller":"traceutil/trace.go:171","msg":"trace[841784178] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:613; }","duration":"2.078702689s","start":"2026-03-19T17:47:39.026295Z","end":"2026-03-19T17:47:41.104998Z","steps":["trace[841784178] 'agreement among raft nodes before linearized reading' (duration: 2.078646108s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:41.105047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.637553277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:47:41.105096Z","caller":"traceutil/trace.go:171","msg":"trace[167673857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"1.637637807s","start":"2026-03-19T17:47:39.467447Z","end":"2026-03-19T17:47:41.105085Z","steps":["trace[167673857] 'agreement among raft nodes before linearized reading' (duration: 1.637573265s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:41.105046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:39.026245Z","time spent":"2.078790451s","remote":"127.0.0.1:51336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-03-19T17:47:41.105125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:39.467425Z","time spent":"1.63769336s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-19T17:47:41.105318Z","caller":"traceutil/trace.go:171","msg":"trace[47704932] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"340.658542ms","start":"2026-03-19T17:47:40.764648Z","end":"2026-03-19T17:47:41.105306Z","steps":["trace[47704932] 'process raft request' (duration: 217.707876ms)","trace[47704932] 'compare' (duration: 122.094425ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:47:41.105382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:40.764624Z","time spent":"340.726333ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:47:41.105441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.050233105s","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-03-19T17:47:41.105525Z","caller":"traceutil/trace.go:171","msg":"trace[1493188171] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:613; }","duration":"2.050303667s","start":"2026-03-19T17:47:39.055176Z","end":"2026-03-19T17:47:41.10548Z","steps":["trace[1493188171] 'agreement among raft nodes before linearized reading' (duration: 2.050115182s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:41.105516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.204171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.177\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-19T17:47:41.105595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:39.055145Z","time spent":"2.050436301s","remote":"127.0.0.1:51360","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":"info","ts":"2026-03-19T17:47:41.105627Z","caller":"traceutil/trace.go:171","msg":"trace[1737576438] range","detail":"{range_begin:/registry/masterleases/199.19.213.177; range_end:; response_count:1; response_revision:613; }","duration":"564.361946ms","start":"2026-03-19T17:47:40.541247Z","end":"2026-03-19T17:47:41.105609Z","steps":["trace[1737576438] 'agreement among raft nodes before linearized reading' (duration: 564.071879ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:41.105847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.073347536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-19T17:47:41.105883Z","caller":"traceutil/trace.go:171","msg":"trace[1978175679] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:613; }","duration":"2.073620942s","start":"2026-03-19T17:47:39.032253Z","end":"2026-03-19T17:47:41.105874Z","steps":["trace[1978175679] 'agreement among raft nodes before linearized reading' (duration: 2.073357506s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:41.105913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:39.032232Z","time spent":"2.073674205s","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T17:47:41.106156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:40.54122Z","time spent":"564.638632ms","remote":"127.0.0.1:51168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.177\" "} {"level":"info","ts":"2026-03-19T17:47:41.368045Z","caller":"traceutil/trace.go:171","msg":"trace[409699610] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"482.225011ms","start":"2026-03-19T17:47:40.885778Z","end":"2026-03-19T17:47:41.368003Z","steps":["trace[409699610] 'process raft request' (duration: 458.848748ms)","trace[409699610] 'compare' (duration: 23.196269ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:47:41.368218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:40.885755Z","time spent":"482.375766ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:47:41.424487Z","caller":"traceutil/trace.go:171","msg":"trace[1810936803] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:639; }","duration":"313.538426ms","start":"2026-03-19T17:47:41.110893Z","end":"2026-03-19T17:47:41.424431Z","steps":["trace[1810936803] 'read index received' (duration: 233.822562ms)","trace[1810936803] 'applied index is now lower than readState.Index' (duration: 79.714224ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:47:41.424787Z","caller":"traceutil/trace.go:171","msg":"trace[1362902095] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"315.228667ms","start":"2026-03-19T17:47:41.109329Z","end":"2026-03-19T17:47:41.424557Z","steps":["trace[1362902095] 'process raft request' (duration: 314.50748ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:41.424975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:41.109312Z","time spent":"315.531675ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:47:41.424479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:41.107666Z","time spent":"316.802677ms","remote":"127.0.0.1:51168","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-19T17:47:41.425849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.246434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:47:41.425895Z","caller":"traceutil/trace.go:171","msg":"trace[1848788280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"312.324526ms","start":"2026-03-19T17:47:41.113558Z","end":"2026-03-19T17:47:41.425883Z","steps":["trace[1848788280] 'agreement among raft nodes before linearized reading' (duration: 312.150642ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:41.425927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:41.113542Z","time spent":"312.372627ms","remote":"127.0.0.1:51126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-19T17:47:41.426102Z","caller":"traceutil/trace.go:171","msg":"trace[198264090] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"315.796452ms","start":"2026-03-19T17:47:41.110279Z","end":"2026-03-19T17:47:41.426076Z","steps":["trace[198264090] 'process raft request' (duration: 313.67786ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:41.426266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:41.110269Z","time spent":"315.901344ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:47:41.426806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.895224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.189e4f3c40096adb\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-03-19T17:47:41.42694Z","caller":"traceutil/trace.go:171","msg":"trace[1679356964] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.189e4f3c40096adb; range_end:; response_count:1; response_revision:616; }","duration":"316.118759ms","start":"2026-03-19T17:47:41.110796Z","end":"2026-03-19T17:47:41.426915Z","steps":["trace[1679356964] 'agreement among raft nodes before linearized reading' (duration: 314.434208ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:41.427001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:41.110769Z","time spent":"316.209261ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.189e4f3c40096adb\" "} {"level":"info","ts":"2026-03-19T17:47:41.650631Z","caller":"traceutil/trace.go:171","msg":"trace[845383870] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"113.05184ms","start":"2026-03-19T17:47:41.537537Z","end":"2026-03-19T17:47:41.650589Z","steps":["trace[845383870] 'process raft request' (duration: 111.378869ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:41.650615Z","caller":"traceutil/trace.go:171","msg":"trace[1519244635] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"112.870977ms","start":"2026-03-19T17:47:41.537708Z","end":"2026-03-19T17:47:41.650579Z","steps":["trace[1519244635] 'read index received' (duration: 111.129464ms)","trace[1519244635] 'applied index is now lower than readState.Index' (duration: 1.739823ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:47:41.650919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.187214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4cg7f\" ","response":"range_response_count:1 size:17149"} {"level":"info","ts":"2026-03-19T17:47:41.650968Z","caller":"traceutil/trace.go:171","msg":"trace[320202459] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4cg7f; range_end:; response_count:1; response_revision:619; }","duration":"113.288736ms","start":"2026-03-19T17:47:41.537667Z","end":"2026-03-19T17:47:41.650955Z","steps":["trace[320202459] 'agreement among raft nodes before linearized reading' (duration: 113.016739ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:41.815185Z","caller":"traceutil/trace.go:171","msg":"trace[63688949] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"149.423403ms","start":"2026-03-19T17:47:41.665737Z","end":"2026-03-19T17:47:41.815161Z","steps":["trace[63688949] 'process raft request' (duration: 126.781868ms)","trace[63688949] 'compare' (duration: 22.515242ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:47:44.065998Z","caller":"traceutil/trace.go:171","msg":"trace[1870615557] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"319.564952ms","start":"2026-03-19T17:47:43.746414Z","end":"2026-03-19T17:47:44.065979Z","steps":["trace[1870615557] 'process raft request' (duration: 319.456519ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:44.066102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:43.746388Z","time spent":"319.668364ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:47:44.072031Z","caller":"traceutil/trace.go:171","msg":"trace[1275182936] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"125.140589ms","start":"2026-03-19T17:47:43.946875Z","end":"2026-03-19T17:47:44.072016Z","steps":["trace[1275182936] 'process raft request' (duration: 125.053597ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:46.036261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.437872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-03-19T17:47:46.036385Z","caller":"traceutil/trace.go:171","msg":"trace[845581634] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:685; }","duration":"291.613786ms","start":"2026-03-19T17:47:45.744745Z","end":"2026-03-19T17:47:46.036359Z","steps":["trace[845581634] 'range keys from in-memory index tree' (duration: 291.234437ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:46.088743Z","caller":"traceutil/trace.go:171","msg":"trace[1729912183] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:713; }","duration":"143.87622ms","start":"2026-03-19T17:47:45.944823Z","end":"2026-03-19T17:47:46.088699Z","steps":["trace[1729912183] 'read index received' (duration: 54.155238ms)","trace[1729912183] 'applied index is now lower than readState.Index' (duration: 89.718912ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:47:46.089031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.165018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-03-19T17:47:46.089174Z","caller":"traceutil/trace.go:171","msg":"trace[1854279760] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:685; }","duration":"144.325822ms","start":"2026-03-19T17:47:45.944819Z","end":"2026-03-19T17:47:46.089144Z","steps":["trace[1854279760] 'agreement among raft nodes before linearized reading' (duration: 143.991644ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:46.755409Z","caller":"traceutil/trace.go:171","msg":"trace[429556630] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"157.386572ms","start":"2026-03-19T17:47:46.597984Z","end":"2026-03-19T17:47:46.755371Z","steps":["trace[429556630] 'process raft request' (duration: 157.116765ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:47.243931Z","caller":"traceutil/trace.go:171","msg":"trace[2067514709] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"125.726208ms","start":"2026-03-19T17:47:47.118171Z","end":"2026-03-19T17:47:47.243897Z","steps":["trace[2067514709] 'process raft request' (duration: 65.154374ms)","trace[2067514709] 'compare' (duration: 60.41454ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:47:47.281097Z","caller":"traceutil/trace.go:171","msg":"trace[1086402619] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"153.089825ms","start":"2026-03-19T17:47:47.127982Z","end":"2026-03-19T17:47:47.281072Z","steps":["trace[1086402619] 'process raft request' (duration: 152.700166ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:47.281508Z","caller":"traceutil/trace.go:171","msg":"trace[431207252] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:718; }","duration":"139.15729ms","start":"2026-03-19T17:47:47.141848Z","end":"2026-03-19T17:47:47.281006Z","steps":["trace[431207252] 'read index received' (duration: 41.553306ms)","trace[431207252] 'applied index is now lower than readState.Index' (duration: 97.603144ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:47:47.281499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.601861ms","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-03-19T17:47:47.281694Z","caller":"traceutil/trace.go:171","msg":"trace[958716812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:691; }","duration":"139.855728ms","start":"2026-03-19T17:47:47.141819Z","end":"2026-03-19T17:47:47.281675Z","steps":["trace[958716812] 'agreement among raft nodes before linearized reading' (duration: 139.395717ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:47.282459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.009992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-03-19T17:47:47.282549Z","caller":"traceutil/trace.go:171","msg":"trace[321399703] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:691; }","duration":"135.130524ms","start":"2026-03-19T17:47:47.147396Z","end":"2026-03-19T17:47:47.282526Z","steps":["trace[321399703] 'agreement among raft nodes before linearized reading' (duration: 134.823307ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:53.607117Z","caller":"traceutil/trace.go:171","msg":"trace[147380837] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"135.652194ms","start":"2026-03-19T17:47:53.471423Z","end":"2026-03-19T17:47:53.607076Z","steps":["trace[147380837] 'process raft request' (duration: 135.400028ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:48:11.540248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.868286ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:48:11.540413Z","caller":"traceutil/trace.go:171","msg":"trace[1931119529] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:853; }","duration":"108.0379ms","start":"2026-03-19T17:48:11.43236Z","end":"2026-03-19T17:48:11.540398Z","steps":["trace[1931119529] 'range keys from in-memory index tree' (duration: 107.855726ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:48:21.595893Z","caller":"traceutil/trace.go:171","msg":"trace[1841241756] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:918; }","duration":"127.618654ms","start":"2026-03-19T17:48:21.468249Z","end":"2026-03-19T17:48:21.595868Z","steps":["trace[1841241756] 'read index received' (duration: 127.47815ms)","trace[1841241756] 'applied index is now lower than readState.Index' (duration: 139.194µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:48:21.59595Z","caller":"traceutil/trace.go:171","msg":"trace[127243064] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"233.287536ms","start":"2026-03-19T17:48:21.362617Z","end":"2026-03-19T17:48:21.595905Z","steps":["trace[127243064] 'process raft request' (duration: 233.073262ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:48:21.596018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.756306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:48:21.596291Z","caller":"traceutil/trace.go:171","msg":"trace[882494651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:882; }","duration":"128.070134ms","start":"2026-03-19T17:48:21.468202Z","end":"2026-03-19T17:48:21.596272Z","steps":["trace[882494651] 'agreement among raft nodes before linearized reading' (duration: 127.764658ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:48:32.177026Z","caller":"traceutil/trace.go:171","msg":"trace[413920328] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:949; }","duration":"220.461792ms","start":"2026-03-19T17:48:31.956537Z","end":"2026-03-19T17:48:32.176999Z","steps":["trace[413920328] 'read index received' (duration: 7.92769ms)","trace[413920328] 'applied index is now lower than readState.Index' (duration: 212.532662ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:48:32.177123Z","caller":"traceutil/trace.go:171","msg":"trace[772370378] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"220.578265ms","start":"2026-03-19T17:48:31.95649Z","end":"2026-03-19T17:48:32.177069Z","steps":["trace[772370378] 'process raft request' (duration: 220.236908ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:48:32.177285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.720089ms","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-03-19T17:48:32.177331Z","caller":"traceutil/trace.go:171","msg":"trace[1562077277] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:912; }","duration":"220.81471ms","start":"2026-03-19T17:48:31.956504Z","end":"2026-03-19T17:48:32.177319Z","steps":["trace[1562077277] 'agreement among raft nodes before linearized reading' (duration: 220.629847ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:48:32.177478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.230032ms","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-03-19T17:48:32.177604Z","caller":"traceutil/trace.go:171","msg":"trace[1132763642] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:912; }","duration":"216.375275ms","start":"2026-03-19T17:48:31.961178Z","end":"2026-03-19T17:48:32.177554Z","steps":["trace[1132763642] 'agreement among raft nodes before linearized reading' (duration: 216.12292ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:48:32.177807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.662118ms","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-03-19T17:48:32.177892Z","caller":"traceutil/trace.go:171","msg":"trace[1189372671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:912; }","duration":"220.83986ms","start":"2026-03-19T17:48:31.957032Z","end":"2026-03-19T17:48:32.177872Z","steps":["trace[1189372671] 'agreement among raft nodes before linearized reading' (duration: 220.150606ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:48:32.415965Z","caller":"traceutil/trace.go:171","msg":"trace[561927923] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"234.602484ms","start":"2026-03-19T17:48:32.181332Z","end":"2026-03-19T17:48:32.415935Z","steps":["trace[561927923] 'process raft request' (duration: 229.540365ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:48:32.416213Z","caller":"traceutil/trace.go:171","msg":"trace[729835722] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"233.651773ms","start":"2026-03-19T17:48:32.182534Z","end":"2026-03-19T17:48:32.416186Z","steps":["trace[729835722] 'process raft request' (duration: 233.357287ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:48:32.416662Z","caller":"traceutil/trace.go:171","msg":"trace[1430697757] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"213.320508ms","start":"2026-03-19T17:48:32.203308Z","end":"2026-03-19T17:48:32.416629Z","steps":["trace[1430697757] 'process raft request' (duration: 212.817488ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:49:10.482682Z","caller":"traceutil/trace.go:171","msg":"trace[693044053] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1100; }","duration":"106.848337ms","start":"2026-03-19T17:49:10.375815Z","end":"2026-03-19T17:49:10.482663Z","steps":["trace[693044053] 'read index received' (duration: 106.670283ms)","trace[693044053] 'applied index is now lower than readState.Index' (duration: 177.194µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:49:10.482731Z","caller":"traceutil/trace.go:171","msg":"trace[2013985065] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"255.898251ms","start":"2026-03-19T17:49:10.226805Z","end":"2026-03-19T17:49:10.482703Z","steps":["trace[2013985065] 'process raft request' (duration: 255.744218ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:49:10.482889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.059141ms","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-03-19T17:49:10.482973Z","caller":"traceutil/trace.go:171","msg":"trace[1570541838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1053; }","duration":"107.180963ms","start":"2026-03-19T17:49:10.375769Z","end":"2026-03-19T17:49:10.48295Z","steps":["trace[1570541838] 'agreement among raft nodes before linearized reading' (duration: 106.96866ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:49:10.969152Z","caller":"traceutil/trace.go:171","msg":"trace[583661963] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1101; }","duration":"469.876631ms","start":"2026-03-19T17:49:10.499237Z","end":"2026-03-19T17:49:10.969114Z","steps":["trace[583661963] 'read index received' (duration: 437.750729ms)","trace[583661963] 'applied index is now lower than readState.Index' (duration: 32.124432ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:49:10.969327Z","caller":"traceutil/trace.go:171","msg":"trace[538528211] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"482.68123ms","start":"2026-03-19T17:49:10.486607Z","end":"2026-03-19T17:49:10.969288Z","steps":["trace[538528211] 'process raft request' (duration: 450.470177ms)","trace[538528211] 'compare' (duration: 31.845966ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:49:10.969507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:49:10.486587Z","time spent":"482.823975ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:49:10.969557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.788396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.177\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-19T17:49:10.969641Z","caller":"traceutil/trace.go:171","msg":"trace[2082776394] range","detail":"{range_begin:/registry/masterleases/199.19.213.177; range_end:; response_count:1; response_revision:1054; }","duration":"423.91896ms","start":"2026-03-19T17:49:10.545705Z","end":"2026-03-19T17:49:10.969624Z","steps":["trace[2082776394] 'agreement among raft nodes before linearized reading' (duration: 423.670174ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:49:10.969681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:49:10.545686Z","time spent":"423.986001ms","remote":"127.0.0.1:51168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.177\" "} {"level":"warn","ts":"2026-03-19T17:49:10.969727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.386191ms","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-03-19T17:49:10.969819Z","caller":"traceutil/trace.go:171","msg":"trace[1714777738] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1054; }","duration":"470.601806ms","start":"2026-03-19T17:49:10.499201Z","end":"2026-03-19T17:49:10.969803Z","steps":["trace[1714777738] 'agreement among raft nodes before linearized reading' (duration: 470.181247ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:49:10.96992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:49:10.499187Z","time spent":"470.715248ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T17:49:10.970022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.725585ms","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-03-19T17:49:10.970182Z","caller":"traceutil/trace.go:171","msg":"trace[233313346] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1054; }","duration":"245.961028ms","start":"2026-03-19T17:49:10.724191Z","end":"2026-03-19T17:49:10.970152Z","steps":["trace[233313346] 'agreement among raft nodes before linearized reading' (duration: 245.461728ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:49:10.970547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.268181ms","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-03-19T17:49:10.970667Z","caller":"traceutil/trace.go:171","msg":"trace[1249834919] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1054; }","duration":"229.443394ms","start":"2026-03-19T17:49:10.741199Z","end":"2026-03-19T17:49:10.970643Z","steps":["trace[1249834919] 'agreement among raft nodes before linearized reading' (duration: 228.349902ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:49:11.140707Z","caller":"traceutil/trace.go:171","msg":"trace[2090683422] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"166.80416ms","start":"2026-03-19T17:49:10.973872Z","end":"2026-03-19T17:49:11.140676Z","steps":["trace[2090683422] 'process raft request' (duration: 166.561876ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:49:11.141011Z","caller":"traceutil/trace.go:171","msg":"trace[2141422088] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1102; }","duration":"165.730749ms","start":"2026-03-19T17:49:10.975247Z","end":"2026-03-19T17:49:11.140977Z","steps":["trace[2141422088] 'read index received' (duration: 82.367509ms)","trace[2141422088] 'applied index is now lower than readState.Index' (duration: 83.36161ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:49:11.141058Z","caller":"traceutil/trace.go:171","msg":"trace[28477478] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"166.442212ms","start":"2026-03-19T17:49:10.974601Z","end":"2026-03-19T17:49:11.141043Z","steps":["trace[28477478] 'process raft request' (duration: 166.033044ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:49:11.141134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.861431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:49:11.141214Z","caller":"traceutil/trace.go:171","msg":"trace[486257825] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"166.049365ms","start":"2026-03-19T17:49:10.975152Z","end":"2026-03-19T17:49:11.141201Z","steps":["trace[486257825] 'process raft request' (duration: 165.695557ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:49:11.141214Z","caller":"traceutil/trace.go:171","msg":"trace[95392821] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1057; }","duration":"165.936253ms","start":"2026-03-19T17:49:10.975226Z","end":"2026-03-19T17:49:11.141162Z","steps":["trace[95392821] 'agreement among raft nodes before linearized reading' (duration: 165.854032ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:49:11.165228Z","caller":"traceutil/trace.go:171","msg":"trace[1123437940] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"105.241533ms","start":"2026-03-19T17:49:11.059958Z","end":"2026-03-19T17:49:11.165199Z","steps":["trace[1123437940] 'process raft request' (duration: 105.08936ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:49:16.008886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.556671ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-19T17:49:20.799379Z","caller":"traceutil/trace.go:171","msg":"trace[1172986160] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1137; }","duration":"107.09709ms","start":"2026-03-19T17:49:20.692256Z","end":"2026-03-19T17:49:20.799353Z","steps":["trace[1172986160] 'read index received' (duration: 106.884506ms)","trace[1172986160] 'applied index is now lower than readState.Index' (duration: 211.854µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:49:20.799569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.299934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:get-nodes\" ","response":"range_response_count:1 size:469"} {"level":"info","ts":"2026-03-19T17:49:20.799636Z","caller":"traceutil/trace.go:171","msg":"trace[1075120233] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:get-nodes; range_end:; response_count:1; response_revision:1087; }","duration":"107.420438ms","start":"2026-03-19T17:49:20.692198Z","end":"2026-03-19T17:49:20.799619Z","steps":["trace[1075120233] 'agreement among raft nodes before linearized reading' (duration: 107.291895ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:49:20.799547Z","caller":"traceutil/trace.go:171","msg":"trace[10871076] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"108.03563ms","start":"2026-03-19T17:49:20.691454Z","end":"2026-03-19T17:49:20.79949Z","steps":["trace[10871076] 'process raft request' (duration: 107.750915ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:49:21.344544Z","caller":"traceutil/trace.go:171","msg":"trace[505557707] linearizableReadLoop","detail":"{readStateIndex:1141; appliedIndex:1140; }","duration":"118.480947ms","start":"2026-03-19T17:49:21.226031Z","end":"2026-03-19T17:49:21.344512Z","steps":["trace[505557707] 'read index received' (duration: 116.274543ms)","trace[505557707] 'applied index is now lower than readState.Index' (duration: 2.205044ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:49:21.344756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.711781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:persistent-volume-binder\" ","response":"range_response_count:1 size:1076"} {"level":"info","ts":"2026-03-19T17:49:21.344821Z","caller":"traceutil/trace.go:171","msg":"trace[555243363] range","detail":"{range_begin:/registry/clusterroles/system:controller:persistent-volume-binder; range_end:; response_count:1; response_revision:1090; }","duration":"118.789353ms","start":"2026-03-19T17:49:21.226012Z","end":"2026-03-19T17:49:21.344801Z","steps":["trace[555243363] 'agreement among raft nodes before linearized reading' (duration: 118.656351ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:49:21.344836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.465295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-19T17:49:21.344794Z","caller":"traceutil/trace.go:171","msg":"trace[1257833473] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"125.804404ms","start":"2026-03-19T17:49:21.218914Z","end":"2026-03-19T17:49:21.344719Z","steps":["trace[1257833473] 'process raft request' (duration: 123.491838ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:49:21.344895Z","caller":"traceutil/trace.go:171","msg":"trace[984699417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1090; }","duration":"100.580888ms","start":"2026-03-19T17:49:21.2443Z","end":"2026-03-19T17:49:21.344881Z","steps":["trace[984699417] 'agreement among raft nodes before linearized reading' (duration: 100.381164ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:49:36.025381Z","caller":"traceutil/trace.go:171","msg":"trace[647282432] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1184; }","duration":"107.828408ms","start":"2026-03-19T17:49:35.917511Z","end":"2026-03-19T17:49:36.025339Z","steps":["trace[647282432] 'read index received' (duration: 8.026388ms)","trace[647282432] 'applied index is now lower than readState.Index' (duration: 99.8005ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:49:36.025604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.038512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-19T17:49:36.025699Z","caller":"traceutil/trace.go:171","msg":"trace[1905442516] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:1131; }","duration":"108.220997ms","start":"2026-03-19T17:49:35.917456Z","end":"2026-03-19T17:49:36.025677Z","steps":["trace[1905442516] 'agreement among raft nodes before linearized reading' (duration: 107.996532ms)"],"step_count":1}