{"level":"warn","ts":"2026-07-07T20:30:25.747124Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-07T20:30:25.747217Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.213: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.213:2380","--initial-cluster=instance=https://199.19.213.213:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.213:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.213: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-07-07T20:30:25.747282Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-07T20:30:25.747291Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.213:2380"]} {"level":"info","ts":"2026-07-07T20:30:25.74731Z","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-07-07T20:30:25.747994Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.213:2379"]} {"level":"info","ts":"2026-07-07T20:30:25.748078Z","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.213:2380"],"listen-peer-urls":["https://199.19.213.213:2380"],"advertise-client-urls":["https://199.19.213.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.213: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-07-07T20:30:25.756915Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.635816ms"} {"level":"info","ts":"2026-07-07T20:30:25.767788Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3faf6a3dfbe0100","cluster-id":"4c2e50e35fb92a79"} {"level":"info","ts":"2026-07-07T20:30:25.767867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 switched to configuration voters=()"} {"level":"info","ts":"2026-07-07T20:30:25.767898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became follower at term 0"} {"level":"info","ts":"2026-07-07T20:30:25.767916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3faf6a3dfbe0100 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-07T20:30:25.767935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became follower at term 1"} {"level":"info","ts":"2026-07-07T20:30:25.768142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 switched to configuration voters=(17580635279088025856)"} {"level":"warn","ts":"2026-07-07T20:30:25.775536Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-07T20:30:25.777903Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-07T20:30:25.780653Z","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-07-07T20:30:25.783181Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3faf6a3dfbe0100","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-07T20:30:25.785349Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3faf6a3dfbe0100","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-07T20:30:25.783964Z","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-07-07T20:30:25.785558Z","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-07-07T20:30:25.785647Z","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-07-07T20:30:25.785959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 switched to configuration voters=(17580635279088025856)"} {"level":"info","ts":"2026-07-07T20:30:25.786258Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4c2e50e35fb92a79","local-member-id":"f3faf6a3dfbe0100","added-peer-id":"f3faf6a3dfbe0100","added-peer-peer-urls":["https://199.19.213.213:2380"]} {"level":"info","ts":"2026-07-07T20:30:25.788304Z","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-07-07T20:30:25.788504Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.213:2380"} {"level":"info","ts":"2026-07-07T20:30:25.78856Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.213:2380"} {"level":"info","ts":"2026-07-07T20:30:25.788681Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3faf6a3dfbe0100","initial-advertise-peer-urls":["https://199.19.213.213:2380"],"listen-peer-urls":["https://199.19.213.213:2380"],"advertise-client-urls":["https://199.19.213.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-07T20:30:25.788727Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-07T20:30:26.16844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 is starting a new election at term 1"} {"level":"info","ts":"2026-07-07T20:30:26.168589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-07T20:30:26.16864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 received MsgPreVoteResp from f3faf6a3dfbe0100 at term 1"} {"level":"info","ts":"2026-07-07T20:30:26.168687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became candidate at term 2"} {"level":"info","ts":"2026-07-07T20:30:26.168754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 received MsgVoteResp from f3faf6a3dfbe0100 at term 2"} {"level":"info","ts":"2026-07-07T20:30:26.168808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became leader at term 2"} {"level":"info","ts":"2026-07-07T20:30:26.168854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3faf6a3dfbe0100 elected leader f3faf6a3dfbe0100 at term 2"} {"level":"info","ts":"2026-07-07T20:30:26.612323Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T20:30:26.616627Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T20:30:26.616602Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3faf6a3dfbe0100","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.213:2379]}","request-path":"/0/members/f3faf6a3dfbe0100/attributes","cluster-id":"4c2e50e35fb92a79","publish-timeout":"7s"} {"level":"info","ts":"2026-07-07T20:30:26.616689Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T20:30:26.617219Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-07T20:30:26.617266Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-07T20:30:26.617504Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4c2e50e35fb92a79","local-member-id":"f3faf6a3dfbe0100","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T20:30:26.617617Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T20:30:26.617804Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T20:30:26.620837Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.213:2379"} {"level":"info","ts":"2026-07-07T20:30:26.622933Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-07T20:30:28.123438Z","caller":"traceutil/trace.go:171","msg":"trace[1626505816] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"212.948327ms","start":"2026-07-07T20:30:27.910464Z","end":"2026-07-07T20:30:28.123412Z","steps":["trace[1626505816] 'process raft request' (duration: 211.274509ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:30:28.123609Z","caller":"traceutil/trace.go:171","msg":"trace[1321800994] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"130.62689ms","start":"2026-07-07T20:30:27.992923Z","end":"2026-07-07T20:30:28.12355Z","steps":["trace[1321800994] 'process raft request' (duration: 130.406047ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:30:28.123764Z","caller":"traceutil/trace.go:171","msg":"trace[1040628869] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"130.65704ms","start":"2026-07-07T20:30:27.993079Z","end":"2026-07-07T20:30:28.123736Z","steps":["trace[1040628869] 'process raft request' (duration: 130.310715ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:30:28.123828Z","caller":"traceutil/trace.go:171","msg":"trace[1672355687] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"130.66511ms","start":"2026-07-07T20:30:27.993123Z","end":"2026-07-07T20:30:28.123788Z","steps":["trace[1672355687] 'process raft request' (duration: 130.319966ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:30:28.124112Z","caller":"traceutil/trace.go:171","msg":"trace[74491424] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"130.943643ms","start":"2026-07-07T20:30:27.993149Z","end":"2026-07-07T20:30:28.124093Z","steps":["trace[74491424] 'process raft request' (duration: 130.370997ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:30:28.12426Z","caller":"traceutil/trace.go:171","msg":"trace[2102453683] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"131.041664ms","start":"2026-07-07T20:30:27.993194Z","end":"2026-07-07T20:30:28.124236Z","steps":["trace[2102453683] 'process raft request' (duration: 130.817352ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:30:28.124568Z","caller":"traceutil/trace.go:171","msg":"trace[1519598107] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"113.816227ms","start":"2026-07-07T20:30:28.010729Z","end":"2026-07-07T20:30:28.124545Z","steps":["trace[1519598107] 'process raft request' (duration: 113.769947ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:30:28.124714Z","caller":"traceutil/trace.go:171","msg":"trace[109580399] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"214.029518ms","start":"2026-07-07T20:30:27.91063Z","end":"2026-07-07T20:30:28.124659Z","steps":["trace[109580399] 'read index received' (duration: 95.100195ms)","trace[109580399] 'applied index is now lower than readState.Index' (duration: 118.928603ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:30:28.124634Z","caller":"traceutil/trace.go:171","msg":"trace[1611625803] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"131.357077ms","start":"2026-07-07T20:30:27.993264Z","end":"2026-07-07T20:30:28.124621Z","steps":["trace[1611625803] 'process raft request' (duration: 130.817252ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:30:28.124965Z","caller":"traceutil/trace.go:171","msg":"trace[105251559] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"122.722023ms","start":"2026-07-07T20:30:28.002181Z","end":"2026-07-07T20:30:28.124903Z","steps":["trace[105251559] 'process raft request' (duration: 122.221248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:28.128808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.96081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-07-07T20:30:28.128987Z","caller":"traceutil/trace.go:171","msg":"trace[1396488611] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"217.117831ms","start":"2026-07-07T20:30:27.911785Z","end":"2026-07-07T20:30:28.128903Z","steps":["trace[1396488611] 'agreement among raft nodes before linearized reading' (duration: 216.113731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:28.129487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.225854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-07T20:30:28.129578Z","caller":"traceutil/trace.go:171","msg":"trace[566472831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"218.322575ms","start":"2026-07-07T20:30:27.911218Z","end":"2026-07-07T20:30:28.12954Z","steps":["trace[566472831] 'agreement among raft nodes before linearized reading' (duration: 216.514755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:28.129744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.202005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-07T20:30:28.129836Z","caller":"traceutil/trace.go:171","msg":"trace[349515017] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"132.293186ms","start":"2026-07-07T20:30:27.997507Z","end":"2026-07-07T20:30:28.1298Z","steps":["trace[349515017] 'agreement among raft nodes before linearized reading' (duration: 132.164455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:28.130788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.843027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.213\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-07T20:30:28.13088Z","caller":"traceutil/trace.go:171","msg":"trace[2016887333] range","detail":"{range_begin:/registry/masterleases/199.19.213.213; range_end:; response_count:0; response_revision:11; }","duration":"138.961028ms","start":"2026-07-07T20:30:27.991894Z","end":"2026-07-07T20:30:28.130855Z","steps":["trace[2016887333] 'agreement among raft nodes before linearized reading' (duration: 135.780114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:28.131121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.84932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-07T20:30:28.131263Z","caller":"traceutil/trace.go:171","msg":"trace[831061978] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:11; }","duration":"219.985682ms","start":"2026-07-07T20:30:27.911192Z","end":"2026-07-07T20:30:28.131178Z","steps":["trace[831061978] 'agreement among raft nodes before linearized reading' (duration: 219.464086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:28.133492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.373158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-07T20:30:28.133626Z","caller":"traceutil/trace.go:171","msg":"trace[2068467725] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"222.494429ms","start":"2026-07-07T20:30:27.911106Z","end":"2026-07-07T20:30:28.133601Z","steps":["trace[2068467725] 'agreement among raft nodes before linearized reading' (duration: 213.872376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:28.130701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.526774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-07T20:30:28.133996Z","caller":"traceutil/trace.go:171","msg":"trace[1163441246] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"141.87731ms","start":"2026-07-07T20:30:27.992095Z","end":"2026-07-07T20:30:28.133972Z","steps":["trace[1163441246] 'agreement among raft nodes before linearized reading' (duration: 138.491913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:28.13409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.444179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-07T20:30:28.134129Z","caller":"traceutil/trace.go:171","msg":"trace[1849550450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"223.503759ms","start":"2026-07-07T20:30:27.910617Z","end":"2026-07-07T20:30:28.134121Z","steps":["trace[1849550450] 'agreement among raft nodes before linearized reading' (duration: 221.65916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:28.633718Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72232683855014015,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T20:30:30.133842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.997023572s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-07T20:30:30.134039Z","caller":"traceutil/trace.go:171","msg":"trace[850925830] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.997245564s","start":"2026-07-07T20:30:28.136755Z","end":"2026-07-07T20:30:30.134001Z","steps":["trace[850925830] 'agreement among raft nodes before linearized reading' (duration: 1.997017702s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:30.134287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:28.136716Z","time spent":"1.997400576s","remote":"127.0.0.1:42274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/07 20:30:30 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-07T20:30:30.156932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.319498173s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-07T20:30:30.157057Z","caller":"traceutil/trace.go:171","msg":"trace[841549792] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"2.027215682s","start":"2026-07-07T20:30:28.12982Z","end":"2026-07-07T20:30:30.157036Z","steps":["trace[841549792] 'process raft request' (duration: 707.064432ms)","trace[841549792] 'compare' (duration: 1.319307501s)"],"step_count":2} {"level":"warn","ts":"2026-07-07T20:30:30.157117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:28.12979Z","time spent":"2.027303753s","remote":"127.0.0.1:42670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T20:30:30.452852Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.615861851s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-07T20:30:31.185095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000247957s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-07T20:30:31.185276Z","caller":"traceutil/trace.go:171","msg":"trace[986779147] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000476s","start":"2026-07-07T20:30:29.184779Z","end":"2026-07-07T20:30:31.185255Z","steps":["trace[986779147] 'agreement among raft nodes before linearized reading' (duration: 2.000277528s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:31.185352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:29.18473Z","time spent":"2.000608411s","remote":"127.0.0.1:42264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/07 20:30:31 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-07T20:30:31.841997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.388690361s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-07T20:30:31.842326Z","caller":"traceutil/trace.go:171","msg":"trace[1174231539] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"3.710048825s","start":"2026-07-07T20:30:28.132236Z","end":"2026-07-07T20:30:31.842285Z","steps":["trace[1174231539] 'process raft request' (duration: 2.321005781s)","trace[1174231539] 'compare' (duration: 1.388561929s)"],"step_count":2} {"level":"warn","ts":"2026-07-07T20:30:31.84247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:28.132219Z","time spent":"3.710189337s","remote":"127.0.0.1:42352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T20:30:31.843108Z","caller":"traceutil/trace.go:171","msg":"trace[1625676288] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"3.708727421s","start":"2026-07-07T20:30:28.134335Z","end":"2026-07-07T20:30:31.843062Z","steps":["trace[1625676288] 'process raft request' (duration: 3.708572279s)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:30:31.843197Z","caller":"traceutil/trace.go:171","msg":"trace[2093931835] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"3.707209724s","start":"2026-07-07T20:30:28.135957Z","end":"2026-07-07T20:30:31.843167Z","steps":["trace[2093931835] 'process raft request' (duration: 3.707052942s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:31.843316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:28.135951Z","time spent":"3.707318465s","remote":"127.0.0.1:42740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T20:30:31.843316Z","caller":"traceutil/trace.go:171","msg":"trace[774659152] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"3.707418856s","start":"2026-07-07T20:30:28.135882Z","end":"2026-07-07T20:30:31.843301Z","steps":["trace[774659152] 'process raft request' (duration: 3.707092053s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:31.843339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:28.134319Z","time spent":"3.708863463s","remote":"127.0.0.1:42740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T20:30:31.843525Z","caller":"traceutil/trace.go:171","msg":"trace[1312172669] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"3.702088259s","start":"2026-07-07T20:30:28.141395Z","end":"2026-07-07T20:30:31.843484Z","steps":["trace[1312172669] 'process raft request' (duration: 3.701756276s)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:30:31.843571Z","caller":"traceutil/trace.go:171","msg":"trace[120394047] linearizableReadLoop","detail":"{readStateIndex:23; appliedIndex:14; }","duration":"3.710968335s","start":"2026-07-07T20:30:28.132588Z","end":"2026-07-07T20:30:31.843556Z","steps":["trace[120394047] 'read index received' (duration: 704.564155ms)","trace[120394047] 'applied index is now lower than readState.Index' (duration: 3.00640286s)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:30:31.843361Z","caller":"traceutil/trace.go:171","msg":"trace[1407226858] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"1.683022416s","start":"2026-07-07T20:30:30.160317Z","end":"2026-07-07T20:30:31.84334Z","steps":["trace[1407226858] 'process raft request' (duration: 1.682953455s)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:30:31.84372Z","caller":"traceutil/trace.go:171","msg":"trace[662025316] transaction","detail":"{read_only:false; number_of_response:0; response_revision:18; }","duration":"3.70587341s","start":"2026-07-07T20:30:28.137831Z","end":"2026-07-07T20:30:31.843705Z","steps":["trace[662025316] 'process raft request' (duration: 3.705283954s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:31.843727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:30.160293Z","time spent":"1.683366749s","remote":"127.0.0.1:42670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T20:30:31.843848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:28.141384Z","time spent":"3.70217842s","remote":"127.0.0.1:42404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T20:30:31.843856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:28.13782Z","time spent":"3.705998142s","remote":"127.0.0.1:42352","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T20:30:31.844083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.711479871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-wqxk6\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-07-07T20:30:31.843456Z","caller":"traceutil/trace.go:171","msg":"trace[525994512] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"3.707256715s","start":"2026-07-07T20:30:28.136177Z","end":"2026-07-07T20:30:31.843434Z","steps":["trace[525994512] 'process raft request' (duration: 3.706911991s)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:30:31.84415Z","caller":"traceutil/trace.go:171","msg":"trace[613226306] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-wqxk6; range_end:; response_count:1; response_revision:20; }","duration":"3.711563222s","start":"2026-07-07T20:30:28.13257Z","end":"2026-07-07T20:30:31.844133Z","steps":["trace[613226306] 'agreement among raft nodes before linearized reading' (duration: 3.71138018s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:31.844206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:28.132556Z","time spent":"3.711637432s","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":884,"request content":"key:\"/registry/certificatesigningrequests/csr-wqxk6\" "} {"level":"warn","ts":"2026-07-07T20:30:31.844213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.951573389s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-07T20:30:31.844219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.954084137s","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-07-07T20:30:31.844256Z","caller":"traceutil/trace.go:171","msg":"trace[502467391] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:20; }","duration":"2.95164624s","start":"2026-07-07T20:30:28.892597Z","end":"2026-07-07T20:30:31.844244Z","steps":["trace[502467391] 'agreement among raft nodes before linearized reading' (duration: 2.951570299s)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:30:31.844271Z","caller":"traceutil/trace.go:171","msg":"trace[2134122264] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:20; }","duration":"2.954168017s","start":"2026-07-07T20:30:28.890088Z","end":"2026-07-07T20:30:31.844256Z","steps":["trace[2134122264] 'agreement among raft nodes before linearized reading' (duration: 2.954096166s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:31.844286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.100601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-07T20:30:31.843406Z","caller":"traceutil/trace.go:171","msg":"trace[1163906218] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"3.707350905s","start":"2026-07-07T20:30:28.136048Z","end":"2026-07-07T20:30:31.843399Z","steps":["trace[1163906218] 'process raft request' (duration: 3.707012552s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:31.844318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:28.890069Z","time spent":"2.954232947s","remote":"127.0.0.1:42578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-07-07T20:30:31.844284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.608201456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-07T20:30:31.844349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234367063s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-07-07T20:30:31.844155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.707915413s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"warn","ts":"2026-07-07T20:30:31.844392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.155916661s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-07-07T20:30:31.844428Z","caller":"traceutil/trace.go:171","msg":"trace[884913247] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"2.155948962s","start":"2026-07-07T20:30:29.68847Z","end":"2026-07-07T20:30:31.844419Z","steps":["trace[884913247] 'agreement among raft nodes before linearized reading' (duration: 2.155895721s)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:30:31.844435Z","caller":"traceutil/trace.go:171","msg":"trace[1831163162] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20; }","duration":"3.708207486s","start":"2026-07-07T20:30:28.136211Z","end":"2026-07-07T20:30:31.844418Z","steps":["trace[1831163162] 'agreement among raft nodes before linearized reading' (duration: 3.707834742s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:31.844461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:29.688435Z","time spent":"2.156019562s","remote":"127.0.0.1:42352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-07T20:30:31.844483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.650568124s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-07-07T20:30:31.84449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:28.1362Z","time spent":"3.708273886s","remote":"127.0.0.1:42404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3514,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-07T20:30:31.844522Z","caller":"traceutil/trace.go:171","msg":"trace[1156974501] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"3.650602865s","start":"2026-07-07T20:30:28.193909Z","end":"2026-07-07T20:30:31.844511Z","steps":["trace[1156974501] 'agreement among raft nodes before linearized reading' (duration: 3.650557414s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:31.844557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:28.193898Z","time spent":"3.650649955s","remote":"127.0.0.1:42352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-07T20:30:31.844559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.658934624s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-07-07T20:30:31.844598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.903257788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-07-07T20:30:31.844615Z","caller":"traceutil/trace.go:171","msg":"trace[402663282] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"3.659049415s","start":"2026-07-07T20:30:28.185552Z","end":"2026-07-07T20:30:31.844601Z","steps":["trace[402663282] 'agreement among raft nodes before linearized reading' (duration: 3.658956135s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:31.844447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.655070573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-07-07T20:30:31.844207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:28.136169Z","time spent":"3.707983603s","remote":"127.0.0.1:42740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T20:30:31.844634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.654844721s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-07-07T20:30:31.844659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:28.185477Z","time spent":"3.659170886s","remote":"127.0.0.1:42352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-07-07T20:30:31.844678Z","caller":"traceutil/trace.go:171","msg":"trace[1876842795] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"3.655311415s","start":"2026-07-07T20:30:28.189344Z","end":"2026-07-07T20:30:31.844655Z","steps":["trace[1876842795] 'agreement among raft nodes before linearized reading' (duration: 3.655077913s)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:30:31.844682Z","caller":"traceutil/trace.go:171","msg":"trace[1902885255] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"3.654944251s","start":"2026-07-07T20:30:28.189724Z","end":"2026-07-07T20:30:31.844668Z","steps":["trace[1902885255] 'agreement among raft nodes before linearized reading' (duration: 3.65486295s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:31.844297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:28.892575Z","time spent":"2.95171521s","remote":"127.0.0.1:42592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-07-07T20:30:31.844733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:28.189324Z","time spent":"3.655389476s","remote":"127.0.0.1:42352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-07T20:30:31.844735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:28.18971Z","time spent":"3.655012782s","remote":"127.0.0.1:42352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-07T20:30:31.844802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.659465749s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-node-lease\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-07T20:30:31.844345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:28.136039Z","time spent":"3.708283817s","remote":"127.0.0.1:42740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T20:30:31.844855Z","caller":"traceutil/trace.go:171","msg":"trace[1185699039] range","detail":"{range_begin:/registry/namespaces/kube-node-lease; range_end:; response_count:0; response_revision:20; }","duration":"3.659561571s","start":"2026-07-07T20:30:28.185279Z","end":"2026-07-07T20:30:31.844841Z","steps":["trace[1185699039] 'agreement among raft nodes before linearized reading' (duration: 3.65948636s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:31.843421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:28.135862Z","time spent":"3.707510567s","remote":"127.0.0.1:42740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T20:30:31.844907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:28.185251Z","time spent":"3.659643311s","remote":"127.0.0.1:42352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-node-lease\" "} {"level":"info","ts":"2026-07-07T20:30:31.844383Z","caller":"traceutil/trace.go:171","msg":"trace[1092322174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"1.608360168s","start":"2026-07-07T20:30:30.23601Z","end":"2026-07-07T20:30:31.844371Z","steps":["trace[1092322174] 'agreement among raft nodes before linearized reading' (duration: 1.608221987s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:31.844998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:30.235987Z","time spent":"1.608998794s","remote":"127.0.0.1:42264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-07T20:30:31.844389Z","caller":"traceutil/trace.go:171","msg":"trace[1760676074] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"1.234440033s","start":"2026-07-07T20:30:30.609933Z","end":"2026-07-07T20:30:31.844373Z","steps":["trace[1760676074] 'agreement among raft nodes before linearized reading' (duration: 1.234380583s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:31.845117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:30.609912Z","time spent":"1.23518866s","remote":"127.0.0.1:42352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-07-07T20:30:31.844334Z","caller":"traceutil/trace.go:171","msg":"trace[779443974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"159.173033ms","start":"2026-07-07T20:30:31.685145Z","end":"2026-07-07T20:30:31.844318Z","steps":["trace[779443974] 'agreement among raft nodes before linearized reading' (duration: 159.103172ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:30:31.844633Z","caller":"traceutil/trace.go:171","msg":"trace[403010522] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"2.903291109s","start":"2026-07-07T20:30:28.941334Z","end":"2026-07-07T20:30:31.844625Z","steps":["trace[403010522] 'agreement among raft nodes before linearized reading' (duration: 2.903239558s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:31.845578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:28.941311Z","time spent":"2.904256388s","remote":"127.0.0.1:42352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-07-07T20:30:33.514558Z","caller":"traceutil/trace.go:171","msg":"trace[1104147999] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"221.755959ms","start":"2026-07-07T20:30:33.292764Z","end":"2026-07-07T20:30:33.51452Z","steps":["trace[1104147999] 'process raft request' (duration: 210.288752ms)","trace[1104147999] 'compare' (duration: 11.299985ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:30:33.515483Z","caller":"traceutil/trace.go:171","msg":"trace[1215783447] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"172.153441ms","start":"2026-07-07T20:30:33.343312Z","end":"2026-07-07T20:30:33.515466Z","steps":["trace[1215783447] 'process raft request' (duration: 171.9754ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:30:34.753553Z","caller":"traceutil/trace.go:171","msg":"trace[1544929455] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"109.202706ms","start":"2026-07-07T20:30:34.644324Z","end":"2026-07-07T20:30:34.753526Z","steps":["trace[1544929455] 'process raft request' (duration: 43.4076ms)","trace[1544929455] 'compare' (duration: 65.617464ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:30:36.04919Z","caller":"traceutil/trace.go:171","msg":"trace[292784333] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"342.097279ms","start":"2026-07-07T20:30:35.707055Z","end":"2026-07-07T20:30:36.049152Z","steps":["trace[292784333] 'process raft request' (duration: 341.078118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:36.049364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:35.70704Z","time spent":"342.23351ms","remote":"127.0.0.1:42420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T20:30:36.049403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.711546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-07T20:30:36.049486Z","caller":"traceutil/trace.go:171","msg":"trace[348809929] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:313; }","duration":"337.806577ms","start":"2026-07-07T20:30:35.711659Z","end":"2026-07-07T20:30:36.049466Z","steps":["trace[348809929] 'agreement among raft nodes before linearized reading' (duration: 337.637356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:36.04953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:30:35.711648Z","time spent":"337.873768ms","remote":"127.0.0.1:42352","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":"info","ts":"2026-07-07T20:30:36.049036Z","caller":"traceutil/trace.go:171","msg":"trace[2054121882] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:325; }","duration":"337.346163ms","start":"2026-07-07T20:30:35.711664Z","end":"2026-07-07T20:30:36.04901Z","steps":["trace[2054121882] 'read index received' (duration: 336.445273ms)","trace[2054121882] 'applied index is now lower than readState.Index' (duration: 899.91µs)"],"step_count":2} {"level":"warn","ts":"2026-07-07T20:30:36.049643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.568923ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T20:30:36.049755Z","caller":"traceutil/trace.go:171","msg":"trace[160250608] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:313; }","duration":"313.681154ms","start":"2026-07-07T20:30:35.73606Z","end":"2026-07-07T20:30:36.049741Z","steps":["trace[160250608] 'agreement among raft nodes before linearized reading' (duration: 313.554903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:36.049767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.238074ms","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-07-07T20:30:36.049857Z","caller":"traceutil/trace.go:171","msg":"trace[1625919745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:313; }","duration":"150.376396ms","start":"2026-07-07T20:30:35.899463Z","end":"2026-07-07T20:30:36.04984Z","steps":["trace[1625919745] 'agreement among raft nodes before linearized reading' (duration: 150.211134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:36.049977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.063182ms","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-07-07T20:30:36.050011Z","caller":"traceutil/trace.go:171","msg":"trace[828904777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:313; }","duration":"153.131693ms","start":"2026-07-07T20:30:35.896869Z","end":"2026-07-07T20:30:36.050001Z","steps":["trace[828904777] 'agreement among raft nodes before linearized reading' (duration: 153.071803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:30:38.213578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.40512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-07T20:30:38.213683Z","caller":"traceutil/trace.go:171","msg":"trace[667251625] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:336; }","duration":"101.574952ms","start":"2026-07-07T20:30:38.112085Z","end":"2026-07-07T20:30:38.21366Z","steps":["trace[667251625] 'range keys from in-memory index tree' (duration: 101.25939ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:30:38.362826Z","caller":"traceutil/trace.go:171","msg":"trace[1916088034] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"120.288634ms","start":"2026-07-07T20:30:38.242525Z","end":"2026-07-07T20:30:38.362814Z","steps":["trace[1916088034] 'process raft request' (duration: 86.411894ms)","trace[1916088034] 'compare' (duration: 33.703179ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:30:38.36278Z","caller":"traceutil/trace.go:171","msg":"trace[883814503] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:352; }","duration":"100.38335ms","start":"2026-07-07T20:30:38.262378Z","end":"2026-07-07T20:30:38.362761Z","steps":["trace[883814503] 'read index received' (duration: 30.410816ms)","trace[883814503] 'applied index is now lower than readState.Index' (duration: 69.971494ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T20:30:38.363022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.635543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-07T20:30:38.363048Z","caller":"traceutil/trace.go:171","msg":"trace[245842117] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:340; }","duration":"100.713384ms","start":"2026-07-07T20:30:38.262327Z","end":"2026-07-07T20:30:38.363041Z","steps":["trace[245842117] 'agreement among raft nodes before linearized reading' (duration: 100.634983ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:31:07.432508Z","caller":"traceutil/trace.go:171","msg":"trace[616650832] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"632.517664ms","start":"2026-07-07T20:31:06.79996Z","end":"2026-07-07T20:31:07.432478Z","steps":["trace[616650832] 'process raft request' (duration: 632.380013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:07.432648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:06.799939Z","time spent":"632.648215ms","remote":"127.0.0.1:42306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T20:31:07.771783Z","caller":"traceutil/trace.go:171","msg":"trace[390376776] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"957.8507ms","start":"2026-07-07T20:31:06.813907Z","end":"2026-07-07T20:31:07.771758Z","steps":["trace[390376776] 'process raft request' (duration: 957.644798ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:31:07.771992Z","caller":"traceutil/trace.go:171","msg":"trace[1433909484] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"932.558143ms","start":"2026-07-07T20:31:06.839365Z","end":"2026-07-07T20:31:07.771923Z","steps":["trace[1433909484] 'process raft request' (duration: 932.339092ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:31:07.772111Z","caller":"traceutil/trace.go:171","msg":"trace[54996505] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"925.619767ms","start":"2026-07-07T20:31:06.846477Z","end":"2026-07-07T20:31:07.772097Z","steps":["trace[54996505] 'process raft request' (duration: 925.456235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:07.772196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:06.839321Z","time spent":"932.801266ms","remote":"127.0.0.1:42306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T20:31:07.772208Z","caller":"traceutil/trace.go:171","msg":"trace[761534997] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"927.846804ms","start":"2026-07-07T20:31:06.844328Z","end":"2026-07-07T20:31:07.772175Z","steps":["trace[761534997] 'process raft request' (duration: 927.532512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:07.772242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:06.846463Z","time spent":"925.697767ms","remote":"127.0.0.1:42306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T20:31:07.772245Z","caller":"traceutil/trace.go:171","msg":"trace[183220801] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"923.869562ms","start":"2026-07-07T20:31:06.848327Z","end":"2026-07-07T20:31:07.772196Z","steps":["trace[183220801] 'process raft request' (duration: 923.655531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:07.772326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:06.844302Z","time spent":"927.969675ms","remote":"127.0.0.1:42306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T20:31:07.772048Z","caller":"traceutil/trace.go:171","msg":"trace[70423268] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"932.609544ms","start":"2026-07-07T20:31:06.83943Z","end":"2026-07-07T20:31:07.772039Z","steps":["trace[70423268] 'process raft request' (duration: 932.312482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:07.772436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:06.848316Z","time spent":"924.035114ms","remote":"127.0.0.1:42306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T20:31:07.772526Z","caller":"traceutil/trace.go:171","msg":"trace[605524184] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"922.662702ms","start":"2026-07-07T20:31:06.84985Z","end":"2026-07-07T20:31:07.772513Z","steps":["trace[605524184] 'process raft request' (duration: 922.177408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:07.772473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:06.839425Z","time spent":"933.001747ms","remote":"127.0.0.1:42306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T20:31:07.772006Z","caller":"traceutil/trace.go:171","msg":"trace[132630648] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"932.465873ms","start":"2026-07-07T20:31:06.839526Z","end":"2026-07-07T20:31:07.771992Z","steps":["trace[132630648] 'process raft request' (duration: 932.287391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:07.772592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:06.849833Z","time spent":"922.730752ms","remote":"127.0.0.1:42306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T20:31:07.772641Z","caller":"traceutil/trace.go:171","msg":"trace[1445370339] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"784.836226ms","start":"2026-07-07T20:31:06.987786Z","end":"2026-07-07T20:31:07.772622Z","steps":["trace[1445370339] 'process raft request' (duration: 784.354012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:07.772657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:06.83952Z","time spent":"933.098398ms","remote":"127.0.0.1:42306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T20:31:07.772657Z","caller":"traceutil/trace.go:171","msg":"trace[1119410176] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"896.778641ms","start":"2026-07-07T20:31:06.875861Z","end":"2026-07-07T20:31:07.77264Z","steps":["trace[1119410176] 'process raft request' (duration: 896.214797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:07.772732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:06.987753Z","time spent":"784.943736ms","remote":"127.0.0.1:42306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T20:31:07.772738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:06.87583Z","time spent":"896.875361ms","remote":"127.0.0.1:42306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T20:31:07.772499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:06.813889Z","time spent":"958.182342ms","remote":"127.0.0.1:42306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T20:31:07.77191Z","caller":"traceutil/trace.go:171","msg":"trace[1270072767] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"943.958126ms","start":"2026-07-07T20:31:06.82791Z","end":"2026-07-07T20:31:07.771868Z","steps":["trace[1270072767] 'process raft request' (duration: 943.753264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:07.772953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:06.82789Z","time spent":"945.041695ms","remote":"127.0.0.1:42306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T20:31:08.48024Z","caller":"traceutil/trace.go:171","msg":"trace[1353420924] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.041609839s","start":"2026-07-07T20:31:07.438592Z","end":"2026-07-07T20:31:08.480202Z","steps":["trace[1353420924] 'process raft request' (duration: 1.005830278s)","trace[1353420924] 'compare' (duration: 34.881233ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:31:08.480302Z","caller":"traceutil/trace.go:171","msg":"trace[198820862] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.040321898s","start":"2026-07-07T20:31:07.439964Z","end":"2026-07-07T20:31:08.480286Z","steps":["trace[198820862] 'process raft request' (duration: 1.039865225s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:08.480374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:07.438572Z","time spent":"1.04173997s","remote":"127.0.0.1:42740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T20:31:08.48039Z","caller":"traceutil/trace.go:171","msg":"trace[1307148130] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:549; }","duration":"1.041506148s","start":"2026-07-07T20:31:07.438832Z","end":"2026-07-07T20:31:08.480338Z","steps":["trace[1307148130] 'read index received' (duration: 332.794198ms)","trace[1307148130] 'applied index is now lower than readState.Index' (duration: 708.71083ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:31:08.480486Z","caller":"traceutil/trace.go:171","msg":"trace[333857843] transaction","detail":"{read_only:false; number_of_response:1; response_revision:541; }","duration":"1.040231827s","start":"2026-07-07T20:31:07.440244Z","end":"2026-07-07T20:31:08.480476Z","steps":["trace[333857843] 'process raft request' (duration: 1.039947095s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:08.480408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:07.439952Z","time spent":"1.040377039s","remote":"127.0.0.1:42306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T20:31:08.480582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:07.440237Z","time spent":"1.040272477s","remote":"127.0.0.1:42306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T20:31:08.480684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04183963s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-07T20:31:08.480755Z","caller":"traceutil/trace.go:171","msg":"trace[469130523] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:541; }","duration":"1.041900321s","start":"2026-07-07T20:31:07.438827Z","end":"2026-07-07T20:31:08.480728Z","steps":["trace[469130523] 'agreement among raft nodes before linearized reading' (duration: 1.04176675s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:08.48079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:07.438775Z","time spent":"1.042006831s","remote":"127.0.0.1:42508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-07T20:31:08.481101Z","caller":"traceutil/trace.go:171","msg":"trace[1901153036] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"690.369191ms","start":"2026-07-07T20:31:07.790717Z","end":"2026-07-07T20:31:08.481086Z","steps":["trace[1901153036] 'process raft request' (duration: 690.033428ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:31:08.481106Z","caller":"traceutil/trace.go:171","msg":"trace[1576977979] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"703.092705ms","start":"2026-07-07T20:31:07.778002Z","end":"2026-07-07T20:31:08.481094Z","steps":["trace[1576977979] 'process raft request' (duration: 702.599391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:08.481161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:07.790698Z","time spent":"690.435551ms","remote":"127.0.0.1:42740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T20:31:08.481171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:07.777982Z","time spent":"703.154665ms","remote":"127.0.0.1:42740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T20:31:08.481243Z","caller":"traceutil/trace.go:171","msg":"trace[636899040] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"694.617505ms","start":"2026-07-07T20:31:07.786615Z","end":"2026-07-07T20:31:08.481233Z","steps":["trace[636899040] 'process raft request' (duration: 694.088861ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:31:08.48128Z","caller":"traceutil/trace.go:171","msg":"trace[294533854] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"674.516462ms","start":"2026-07-07T20:31:07.806748Z","end":"2026-07-07T20:31:08.481264Z","steps":["trace[294533854] 'process raft request' (duration: 674.110769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:08.481294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:07.786597Z","time spent":"694.667955ms","remote":"127.0.0.1:42306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T20:31:08.481355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:07.806728Z","time spent":"674.582262ms","remote":"127.0.0.1:42306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T20:31:08.481475Z","caller":"traceutil/trace.go:171","msg":"trace[1500248318] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"674.458211ms","start":"2026-07-07T20:31:07.807006Z","end":"2026-07-07T20:31:08.481464Z","steps":["trace[1500248318] 'process raft request' (duration: 673.912217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:08.481521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:07.806988Z","time spent":"674.513022ms","remote":"127.0.0.1:42740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T20:31:08.481527Z","caller":"traceutil/trace.go:171","msg":"trace[874357092] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"681.655149ms","start":"2026-07-07T20:31:07.799859Z","end":"2026-07-07T20:31:08.481514Z","steps":["trace[874357092] 'process raft request' (duration: 680.942633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:08.481584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:07.79984Z","time spent":"681.715459ms","remote":"127.0.0.1:42740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T20:31:08.497344Z","caller":"traceutil/trace.go:171","msg":"trace[2070426270] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"673.18819ms","start":"2026-07-07T20:31:07.82414Z","end":"2026-07-07T20:31:08.497328Z","steps":["trace[2070426270] 'process raft request' (duration: 672.778407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:08.497454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:07.824123Z","time spent":"673.271021ms","remote":"127.0.0.1:42306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T20:31:08.497574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039618501s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T20:31:08.497646Z","caller":"traceutil/trace.go:171","msg":"trace[64507203] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"654.502339ms","start":"2026-07-07T20:31:07.843135Z","end":"2026-07-07T20:31:08.497638Z","steps":["trace[64507203] 'process raft request' (duration: 653.951244ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:31:08.497649Z","caller":"traceutil/trace.go:171","msg":"trace[1094390434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"1.039810933s","start":"2026-07-07T20:31:07.457819Z","end":"2026-07-07T20:31:08.49763Z","steps":["trace[1094390434] 'agreement among raft nodes before linearized reading' (duration: 1.039615731s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:08.497689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:07.8431Z","time spent":"654.572919ms","remote":"127.0.0.1:42306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T20:31:08.497691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:07.457809Z","time spent":"1.039873473s","remote":"127.0.0.1:42274","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-07-07T20:31:08.497795Z","caller":"traceutil/trace.go:171","msg":"trace[287522076] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"673.579713ms","start":"2026-07-07T20:31:07.824165Z","end":"2026-07-07T20:31:08.497744Z","steps":["trace[287522076] 'process raft request' (duration: 672.875197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:08.49784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:07.824156Z","time spent":"673.662303ms","remote":"127.0.0.1:42306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T20:31:08.497958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.212789ms","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-07-07T20:31:08.497989Z","caller":"traceutil/trace.go:171","msg":"trace[699163344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"291.27494ms","start":"2026-07-07T20:31:08.206706Z","end":"2026-07-07T20:31:08.497981Z","steps":["trace[699163344] 'agreement among raft nodes before linearized reading' (duration: 291.21241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:08.498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.256173ms","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-07-07T20:31:08.498042Z","caller":"traceutil/trace.go:171","msg":"trace[1178989446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"290.306133ms","start":"2026-07-07T20:31:08.207725Z","end":"2026-07-07T20:31:08.498032Z","steps":["trace[1178989446] 'agreement among raft nodes before linearized reading' (duration: 290.162772ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:31:14.851173Z","caller":"traceutil/trace.go:171","msg":"trace[797526769] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"316.229748ms","start":"2026-07-07T20:31:14.534909Z","end":"2026-07-07T20:31:14.851139Z","steps":["trace[797526769] 'read index received' (duration: 316.046098ms)","trace[797526769] 'applied index is now lower than readState.Index' (duration: 181.63µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:31:14.851225Z","caller":"traceutil/trace.go:171","msg":"trace[357605795] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"322.97274ms","start":"2026-07-07T20:31:14.528212Z","end":"2026-07-07T20:31:14.851185Z","steps":["trace[357605795] 'process raft request' (duration: 322.689005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:14.85141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:14.528199Z","time spent":"323.115378ms","remote":"127.0.0.1:42508","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-07-07T20:31:14.851556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.577666ms","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-07-07T20:31:14.85161Z","caller":"traceutil/trace.go:171","msg":"trace[1642686414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"316.711273ms","start":"2026-07-07T20:31:14.534884Z","end":"2026-07-07T20:31:14.851595Z","steps":["trace[1642686414] 'agreement among raft nodes before linearized reading' (duration: 316.420487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:14.851653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:14.534873Z","time spent":"316.770496ms","remote":"127.0.0.1:42508","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-07-07T20:31:15.051931Z","caller":"traceutil/trace.go:171","msg":"trace[771280717] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"252.336368ms","start":"2026-07-07T20:31:14.79954Z","end":"2026-07-07T20:31:15.051877Z","steps":["trace[771280717] 'process raft request' (duration: 251.974558ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:31:15.052455Z","caller":"traceutil/trace.go:171","msg":"trace[1990450502] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"201.154334ms","start":"2026-07-07T20:31:14.851271Z","end":"2026-07-07T20:31:15.052425Z","steps":["trace[1990450502] 'read index received' (duration: 200.794116ms)","trace[1990450502] 'applied index is now lower than readState.Index' (duration: 358.758µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:31:15.052509Z","caller":"traceutil/trace.go:171","msg":"trace[10289835] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"203.108027ms","start":"2026-07-07T20:31:14.849387Z","end":"2026-07-07T20:31:15.052495Z","steps":["trace[10289835] 'process raft request' (duration: 202.506326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:15.052696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.807501ms","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-07-07T20:31:15.052743Z","caller":"traceutil/trace.go:171","msg":"trace[1729167497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"516.885676ms","start":"2026-07-07T20:31:14.535844Z","end":"2026-07-07T20:31:15.05273Z","steps":["trace[1729167497] 'agreement among raft nodes before linearized reading' (duration: 516.696496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:15.05279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:14.535837Z","time spent":"516.942318ms","remote":"127.0.0.1:42508","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-07-07T20:31:15.052965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.690385ms","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-07-07T20:31:15.053017Z","caller":"traceutil/trace.go:171","msg":"trace[1550028340] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"409.786391ms","start":"2026-07-07T20:31:14.643217Z","end":"2026-07-07T20:31:15.053004Z","steps":["trace[1550028340] 'agreement among raft nodes before linearized reading' (duration: 409.554619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:15.053129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:14.643206Z","time spent":"409.911797ms","remote":"127.0.0.1:42508","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-07-07T20:31:15.053193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.45972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tkw8j\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-07T20:31:15.053238Z","caller":"traceutil/trace.go:171","msg":"trace[1855691133] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tkw8j; range_end:; response_count:1; response_revision:628; }","duration":"428.541294ms","start":"2026-07-07T20:31:14.624685Z","end":"2026-07-07T20:31:15.053226Z","steps":["trace[1855691133] 'agreement among raft nodes before linearized reading' (duration: 428.421038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:15.053275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:14.62466Z","time spent":"428.607507ms","remote":"127.0.0.1:42420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-tkw8j\" "} {"level":"info","ts":"2026-07-07T20:31:15.401976Z","caller":"traceutil/trace.go:171","msg":"trace[385850521] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"546.857178ms","start":"2026-07-07T20:31:14.855099Z","end":"2026-07-07T20:31:15.401956Z","steps":["trace[385850521] 'process raft request' (duration: 498.339434ms)","trace[385850521] 'compare' (duration: 48.27588ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T20:31:15.402068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:14.855082Z","time spent":"546.935102ms","remote":"127.0.0.1:42508","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-07-07T20:31:15.435758Z","caller":"traceutil/trace.go:171","msg":"trace[1693717717] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"377.507453ms","start":"2026-07-07T20:31:15.058212Z","end":"2026-07-07T20:31:15.435719Z","steps":["trace[1693717717] 'process raft request' (duration: 377.271961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:15.435921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:15.058183Z","time spent":"377.658431ms","remote":"127.0.0.1:42508","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-07-07T20:31:15.436162Z","caller":"traceutil/trace.go:171","msg":"trace[454371019] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"376.065477ms","start":"2026-07-07T20:31:15.060053Z","end":"2026-07-07T20:31:15.436119Z","steps":["trace[454371019] 'process raft request' (duration: 375.621774ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:31:15.436215Z","caller":"traceutil/trace.go:171","msg":"trace[484534752] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"368.488676ms","start":"2026-07-07T20:31:15.067717Z","end":"2026-07-07T20:31:15.436206Z","steps":["trace[484534752] 'process raft request' (duration: 368.220202ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:31:15.436209Z","caller":"traceutil/trace.go:171","msg":"trace[1196894741] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"322.094313ms","start":"2026-07-07T20:31:15.114095Z","end":"2026-07-07T20:31:15.436189Z","steps":["trace[1196894741] 'process raft request' (duration: 322.04802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:15.436303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:15.114076Z","time spent":"322.181217ms","remote":"127.0.0.1:42558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T20:31:15.436322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:15.060031Z","time spent":"376.215346ms","remote":"127.0.0.1:42508","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-07-07T20:31:15.436536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:15.067704Z","time spent":"368.548999ms","remote":"127.0.0.1:42420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T20:31:16.958643Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72232683855015303,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T20:31:17.398787Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.555863202s","expected-duration":"1s"} {"level":"info","ts":"2026-07-07T20:31:17.400008Z","caller":"traceutil/trace.go:171","msg":"trace[1073053832] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"941.711081ms","start":"2026-07-07T20:31:16.45827Z","end":"2026-07-07T20:31:17.399981Z","steps":["trace[1073053832] 'read index received' (duration: 940.647317ms)","trace[1073053832] 'applied index is now lower than readState.Index' (duration: 1.062404ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:31:17.400132Z","caller":"traceutil/trace.go:171","msg":"trace[799043890] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.561770517s","start":"2026-07-07T20:31:15.838348Z","end":"2026-07-07T20:31:17.400119Z","steps":["trace[799043890] 'process raft request' (duration: 1.560855091s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:17.400251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:15.838327Z","time spent":"1.561866422s","remote":"127.0.0.1:42336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T20:31:17.400277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.982265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T20:31:17.400262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.122216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-07T20:31:17.400402Z","caller":"traceutil/trace.go:171","msg":"trace[844507593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"942.160794ms","start":"2026-07-07T20:31:16.458208Z","end":"2026-07-07T20:31:17.400369Z","steps":["trace[844507593] 'agreement among raft nodes before linearized reading' (duration: 941.904202ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:31:17.400419Z","caller":"traceutil/trace.go:171","msg":"trace[1055576273] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"545.312706ms","start":"2026-07-07T20:31:16.855087Z","end":"2026-07-07T20:31:17.4004Z","steps":["trace[1055576273] 'agreement among raft nodes before linearized reading' (duration: 545.016271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:17.400469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:16.855069Z","time spent":"545.38794ms","remote":"127.0.0.1:42508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-07T20:31:17.400471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:16.458187Z","time spent":"942.26999ms","remote":"127.0.0.1:42264","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-07-07T20:31:17.787968Z","caller":"traceutil/trace.go:171","msg":"trace[2112946885] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"151.48397ms","start":"2026-07-07T20:31:17.636461Z","end":"2026-07-07T20:31:17.787945Z","steps":["trace[2112946885] 'process raft request' (duration: 151.335413ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:31:17.953414Z","caller":"traceutil/trace.go:171","msg":"trace[1047672866] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"302.434903ms","start":"2026-07-07T20:31:17.650934Z","end":"2026-07-07T20:31:17.953369Z","steps":["trace[1047672866] 'process raft request' (duration: 302.052364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:17.95362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:17.650908Z","time spent":"302.597232ms","remote":"127.0.0.1:42420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17232,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T20:31:18.008473Z","caller":"traceutil/trace.go:171","msg":"trace[657440516] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"121.47587ms","start":"2026-07-07T20:31:17.886974Z","end":"2026-07-07T20:31:18.00845Z","steps":["trace[657440516] 'process raft request' (duration: 120.130132ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:31:29.719158Z","caller":"traceutil/trace.go:171","msg":"trace[1053006418] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"249.453758ms","start":"2026-07-07T20:31:29.469681Z","end":"2026-07-07T20:31:29.719134Z","steps":["trace[1053006418] 'read index received' (duration: 249.153705ms)","trace[1053006418] 'applied index is now lower than readState.Index' (duration: 299.063µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:31:29.719265Z","caller":"traceutil/trace.go:171","msg":"trace[1331243713] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"252.295243ms","start":"2026-07-07T20:31:29.466938Z","end":"2026-07-07T20:31:29.719233Z","steps":["trace[1331243713] 'process raft request' (duration: 251.952558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:29.719378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.684653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-07T20:31:29.719378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.674267ms","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-07-07T20:31:29.719415Z","caller":"traceutil/trace.go:171","msg":"trace[1054403186] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:785; }","duration":"220.754276ms","start":"2026-07-07T20:31:29.498652Z","end":"2026-07-07T20:31:29.719406Z","steps":["trace[1054403186] 'agreement among raft nodes before linearized reading' (duration: 220.687803ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:31:29.719419Z","caller":"traceutil/trace.go:171","msg":"trace[654475215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:785; }","duration":"249.753361ms","start":"2026-07-07T20:31:29.469655Z","end":"2026-07-07T20:31:29.719409Z","steps":["trace[654475215] 'agreement among raft nodes before linearized reading' (duration: 249.604724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:29.719477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.109105ms","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-07-07T20:31:29.719541Z","caller":"traceutil/trace.go:171","msg":"trace[948990536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:785; }","duration":"212.22175ms","start":"2026-07-07T20:31:29.507302Z","end":"2026-07-07T20:31:29.719524Z","steps":["trace[948990536] 'agreement among raft nodes before linearized reading' (duration: 212.081564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:29.719651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.931531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-07T20:31:29.719668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.301294ms","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-07-07T20:31:29.719683Z","caller":"traceutil/trace.go:171","msg":"trace[64078469] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:785; }","duration":"194.988842ms","start":"2026-07-07T20:31:29.524685Z","end":"2026-07-07T20:31:29.719674Z","steps":["trace[64078469] 'agreement among raft nodes before linearized reading' (duration: 194.887568ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:31:29.719688Z","caller":"traceutil/trace.go:171","msg":"trace[502193774] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:785; }","duration":"217.350546ms","start":"2026-07-07T20:31:29.502331Z","end":"2026-07-07T20:31:29.719681Z","steps":["trace[502193774] 'agreement among raft nodes before linearized reading' (duration: 217.291933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:32.663018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.265712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T20:31:32.663094Z","caller":"traceutil/trace.go:171","msg":"trace[658147211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"200.385477ms","start":"2026-07-07T20:31:32.46269Z","end":"2026-07-07T20:31:32.663076Z","steps":["trace[658147211] 'range keys from in-memory index tree' (duration: 200.199339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:33.240416Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72232683855015630,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-07T20:31:33.267788Z","caller":"traceutil/trace.go:171","msg":"trace[419622447] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"529.202679ms","start":"2026-07-07T20:31:32.738565Z","end":"2026-07-07T20:31:33.267768Z","steps":["trace[419622447] 'process raft request' (duration: 529.046613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:33.267929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:32.738544Z","time spent":"529.310383ms","remote":"127.0.0.1:42482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T20:31:33.271755Z","caller":"traceutil/trace.go:171","msg":"trace[124585734] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"255.745499ms","start":"2026-07-07T20:31:33.015989Z","end":"2026-07-07T20:31:33.271734Z","steps":["trace[124585734] 'process raft request' (duration: 255.608523ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:31:33.271745Z","caller":"traceutil/trace.go:171","msg":"trace[1502586389] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:831; }","duration":"532.169983ms","start":"2026-07-07T20:31:32.739553Z","end":"2026-07-07T20:31:33.271723Z","steps":["trace[1502586389] 'read index received' (duration: 528.096532ms)","trace[1502586389] 'applied index is now lower than readState.Index' (duration: 4.072451ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T20:31:33.272089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.501926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.213\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-07T20:31:33.272275Z","caller":"traceutil/trace.go:171","msg":"trace[1305904416] range","detail":"{range_begin:/registry/masterleases/199.19.213.213; range_end:; response_count:1; response_revision:804; }","duration":"532.700246ms","start":"2026-07-07T20:31:32.739481Z","end":"2026-07-07T20:31:33.272182Z","steps":["trace[1305904416] 'agreement among raft nodes before linearized reading' (duration: 532.330229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:31:33.272405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:31:32.739465Z","time spent":"532.848282ms","remote":"127.0.0.1:42290","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.213\" "} {"level":"info","ts":"2026-07-07T20:33:58.16435Z","caller":"traceutil/trace.go:171","msg":"trace[797128166] linearizableReadLoop","detail":"{readStateIndex:1519; appliedIndex:1518; }","duration":"101.277297ms","start":"2026-07-07T20:33:58.063035Z","end":"2026-07-07T20:33:58.164313Z","steps":["trace[797128166] 'read index received' (duration: 60.15843ms)","trace[797128166] 'applied index is now lower than readState.Index' (duration: 41.114807ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T20:33:58.164664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.603412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/sh.helm.release.v1.cilium.v1\" ","response":"range_response_count:1 size:317290"} {"level":"info","ts":"2026-07-07T20:33:58.16473Z","caller":"traceutil/trace.go:171","msg":"trace[1894871154] range","detail":"{range_begin:/registry/secrets/kube-system/sh.helm.release.v1.cilium.v1; range_end:; response_count:1; response_revision:1455; }","duration":"101.704963ms","start":"2026-07-07T20:33:58.063007Z","end":"2026-07-07T20:33:58.164712Z","steps":["trace[1894871154] 'agreement among raft nodes before linearized reading' (duration: 101.450749ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:33:58.65078Z","caller":"traceutil/trace.go:171","msg":"trace[1320296684] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"137.599992ms","start":"2026-07-07T20:33:58.513153Z","end":"2026-07-07T20:33:58.650753Z","steps":["trace[1320296684] 'process raft request' (duration: 137.194656ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:33:58.652635Z","caller":"traceutil/trace.go:171","msg":"trace[1408490885] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"135.058945ms","start":"2026-07-07T20:33:58.517544Z","end":"2026-07-07T20:33:58.652603Z","steps":["trace[1408490885] 'process raft request' (duration: 134.924303ms)"],"step_count":1}