{"level":"warn","ts":"2026-02-24T06:22:43.90163Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T06:22:43.901755Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.36:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.36:2380","--initial-cluster=instance=https://162.253.55.36:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.36:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.36:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-24T06:22:43.901866Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T06:22:43.901894Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-02-24T06:22:43.90192Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-24T06:22:43.902874Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"]} {"level":"info","ts":"2026-02-24T06:22:43.902962Z","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://162.253.55.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.36:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-24T06:22:43.907736Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.52595ms"} {"level":"info","ts":"2026-02-24T06:22:43.914238Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cb89ec34a9687f0b","cluster-id":"d8e0d5e222e856c9"} {"level":"info","ts":"2026-02-24T06:22:43.914318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T06:22:43.914346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 0"} {"level":"info","ts":"2026-02-24T06:22:43.914362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cb89ec34a9687f0b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T06:22:43.914373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 1"} {"level":"info","ts":"2026-02-24T06:22:43.914425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"warn","ts":"2026-02-24T06:22:43.921161Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T06:22:43.923019Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T06:22:43.924107Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-24T06:22:43.926333Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cb89ec34a9687f0b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T06:22:43.926573Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cb89ec34a9687f0b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T06:22:43.926673Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-24T06:22:43.926769Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-24T06:22:43.926782Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-24T06:22:43.927473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"info","ts":"2026-02-24T06:22:43.927971Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","added-peer-id":"cb89ec34a9687f0b","added-peer-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-02-24T06:22:43.931752Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-24T06:22:43.931954Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cb89ec34a9687f0b","initial-advertise-peer-urls":["https://162.253.55.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T06:22:43.931978Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T06:22:43.932076Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-02-24T06:22:43.932114Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-02-24T06:22:44.314963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T06:22:44.315062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T06:22:44.315089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgPreVoteResp from cb89ec34a9687f0b at term 1"} {"level":"info","ts":"2026-02-24T06:22:44.315106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became candidate at term 2"} {"level":"info","ts":"2026-02-24T06:22:44.315114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgVoteResp from cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-02-24T06:22:44.315125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became leader at term 2"} {"level":"info","ts":"2026-02-24T06:22:44.315134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cb89ec34a9687f0b elected leader cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-02-24T06:22:44.316566Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T06:22:44.317217Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cb89ec34a9687f0b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.36:2379]}","request-path":"/0/members/cb89ec34a9687f0b/attributes","cluster-id":"d8e0d5e222e856c9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T06:22:44.317229Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T06:22:44.317306Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T06:22:44.317631Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T06:22:44.317705Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T06:22:44.320507Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T06:22:44.321648Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.36:2379"} {"level":"info","ts":"2026-02-24T06:22:44.322724Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T06:22:44.323757Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T06:22:44.326952Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T06:23:10.335904Z","caller":"traceutil/trace.go:171","msg":"trace[1624040534] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"331.087177ms","start":"2026-02-24T06:23:10.004779Z","end":"2026-02-24T06:23:10.335866Z","steps":["trace[1624040534] 'read index received' (duration: 330.833741ms)","trace[1624040534] 'applied index is now lower than readState.Index' (duration: 252.266µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:23:10.335979Z","caller":"traceutil/trace.go:171","msg":"trace[1779168496] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"340.928205ms","start":"2026-02-24T06:23:09.995004Z","end":"2026-02-24T06:23:10.335933Z","steps":["trace[1779168496] 'process raft request' (duration: 340.68603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:10.336059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.262251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T06:23:10.336153Z","caller":"traceutil/trace.go:171","msg":"trace[2135632458] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:468; }","duration":"331.402014ms","start":"2026-02-24T06:23:10.004735Z","end":"2026-02-24T06:23:10.336137Z","steps":["trace[2135632458] 'agreement among raft nodes before linearized reading' (duration: 331.252271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:10.336181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:10.004717Z","time spent":"331.457696ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T06:23:10.336643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:09.994973Z","time spent":"341.096119ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:23:10.56508Z","caller":"traceutil/trace.go:171","msg":"trace[614629036] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"206.779248ms","start":"2026-02-24T06:23:10.358247Z","end":"2026-02-24T06:23:10.565027Z","steps":["trace[614629036] 'read index received' (duration: 179.595952ms)","trace[614629036] 'applied index is now lower than readState.Index' (duration: 27.181146ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:23:10.565314Z","caller":"traceutil/trace.go:171","msg":"trace[2001335300] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"224.514167ms","start":"2026-02-24T06:23:10.340779Z","end":"2026-02-24T06:23:10.565293Z","steps":["trace[2001335300] 'process raft request' (duration: 197.096095ms)","trace[2001335300] 'compare' (duration: 26.973511ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:23:10.565733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.473246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-02-24T06:23:10.565826Z","caller":"traceutil/trace.go:171","msg":"trace[185681461] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:469; }","duration":"207.606998ms","start":"2026-02-24T06:23:10.3582Z","end":"2026-02-24T06:23:10.565807Z","steps":["trace[185681461] 'agreement among raft nodes before linearized reading' (duration: 207.242039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:11.066785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.935285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T06:23:11.066902Z","caller":"traceutil/trace.go:171","msg":"trace[2014650717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:470; }","duration":"239.065238ms","start":"2026-02-24T06:23:10.82778Z","end":"2026-02-24T06:23:11.066845Z","steps":["trace[2014650717] 'range keys from in-memory index tree' (duration: 238.76524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:13.697318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.028186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:23:13.697438Z","caller":"traceutil/trace.go:171","msg":"trace[1650269978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"491.221451ms","start":"2026-02-24T06:23:13.206185Z","end":"2026-02-24T06:23:13.697406Z","steps":["trace[1650269978] 'range keys from in-memory index tree' (duration: 490.917193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:13.697491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:13.206162Z","time spent":"491.321243ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T06:23:23.143883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.239962551s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xdgqs\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-24T06:23:23.143968Z","caller":"traceutil/trace.go:171","msg":"trace[930673261] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xdgqs; range_end:; response_count:1; response_revision:529; }","duration":"3.240080434s","start":"2026-02-24T06:23:19.903868Z","end":"2026-02-24T06:23:23.143948Z","steps":["trace[930673261] 'range keys from in-memory index tree' (duration: 3.239841248s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:23.144011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:19.90385Z","time spent":"3.240147806s","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16676,"request content":"key:\"/registry/pods/kube-system/cilium-xdgqs\" "} {"level":"warn","ts":"2026-02-24T06:23:23.14447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.937383077s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:23:23.144562Z","caller":"traceutil/trace.go:171","msg":"trace[562306533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"1.937505289s","start":"2026-02-24T06:23:21.207033Z","end":"2026-02-24T06:23:23.144538Z","steps":["trace[562306533] 'range keys from in-memory index tree' (duration: 1.937254894s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:23.144575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.514185322s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-24T06:23:23.144601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:21.207018Z","time spent":"1.937574352s","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T06:23:23.144612Z","caller":"traceutil/trace.go:171","msg":"trace[1649967209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:529; }","duration":"2.514264164s","start":"2026-02-24T06:23:20.630337Z","end":"2026-02-24T06:23:23.144601Z","steps":["trace[1649967209] 'range keys from in-memory index tree' (duration: 2.514017898s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:23.144642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:20.630312Z","time spent":"2.514323166s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T06:23:23.144885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.522656176s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-24T06:23:23.144874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.767984236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T06:23:23.144914Z","caller":"traceutil/trace.go:171","msg":"trace[477857909] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"2.522722758s","start":"2026-02-24T06:23:20.622183Z","end":"2026-02-24T06:23:23.144905Z","steps":["trace[477857909] 'range keys from in-memory index tree' (duration: 2.521749205s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:23.144209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.023024663s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-24T06:23:23.144942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:20.622162Z","time spent":"2.522773899s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T06:23:23.144951Z","caller":"traceutil/trace.go:171","msg":"trace[1505278475] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:529; }","duration":"2.768104288s","start":"2026-02-24T06:23:20.376825Z","end":"2026-02-24T06:23:23.144929Z","steps":["trace[1505278475] 'range keys from in-memory index tree' (duration: 2.767792251s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:23.144997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:20.376803Z","time spent":"2.76818574s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T06:23:23.145003Z","caller":"traceutil/trace.go:171","msg":"trace[1361025656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"2.023845293s","start":"2026-02-24T06:23:21.121137Z","end":"2026-02-24T06:23:23.144983Z","steps":["trace[1361025656] 'range keys from in-memory index tree' (duration: 2.022894771s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:23.145044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:21.121119Z","time spent":"2.023913215s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T06:23:23.183212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.387361ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-24T06:23:23.57676Z","caller":"traceutil/trace.go:171","msg":"trace[450954003] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"425.995706ms","start":"2026-02-24T06:23:23.150738Z","end":"2026-02-24T06:23:23.576733Z","steps":["trace[450954003] 'process raft request' (duration: 425.846923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:23.576906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:23.150722Z","time spent":"426.11115ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:23:23.705326Z","caller":"traceutil/trace.go:171","msg":"trace[1004763204] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"554.527598ms","start":"2026-02-24T06:23:23.150774Z","end":"2026-02-24T06:23:23.705301Z","steps":["trace[1004763204] 'process raft request' (duration: 554.361124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:23.705481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:23.150751Z","time spent":"554.65221ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:23:23.705731Z","caller":"traceutil/trace.go:171","msg":"trace[1254816894] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"554.137957ms","start":"2026-02-24T06:23:23.151582Z","end":"2026-02-24T06:23:23.70572Z","steps":["trace[1254816894] 'process raft request' (duration: 553.976733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:23.705827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:23.15157Z","time spent":"554.217339ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:23:23.705926Z","caller":"traceutil/trace.go:171","msg":"trace[638507178] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"554.331093ms","start":"2026-02-24T06:23:23.151565Z","end":"2026-02-24T06:23:23.705896Z","steps":["trace[638507178] 'process raft request' (duration: 553.697507ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:23:23.705954Z","caller":"traceutil/trace.go:171","msg":"trace[1792758657] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"547.57074ms","start":"2026-02-24T06:23:23.158333Z","end":"2026-02-24T06:23:23.705903Z","steps":["trace[1792758657] 'process raft request' (duration: 547.332194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:23.706081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:23.151539Z","time spent":"554.456695ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:23:23.706141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:23.158314Z","time spent":"547.722463ms","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:23:23.705742Z","caller":"traceutil/trace.go:171","msg":"trace[595524586] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:551; }","duration":"514.060281ms","start":"2026-02-24T06:23:23.191657Z","end":"2026-02-24T06:23:23.705717Z","steps":["trace[595524586] 'read index received' (duration: 384.989777ms)","trace[595524586] 'applied index is now lower than readState.Index' (duration: 129.069644ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:23:23.706222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.553463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:23:23.706315Z","caller":"traceutil/trace.go:171","msg":"trace[1774072126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"514.653725ms","start":"2026-02-24T06:23:23.191641Z","end":"2026-02-24T06:23:23.706295Z","steps":["trace[1774072126] 'agreement among raft nodes before linearized reading' (duration: 514.520332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:23.706355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:23.191579Z","time spent":"514.767799ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T06:23:24.672914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.299312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:23:24.673Z","caller":"traceutil/trace.go:171","msg":"trace[676843756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"469.440225ms","start":"2026-02-24T06:23:24.203542Z","end":"2026-02-24T06:23:24.672982Z","steps":["trace[676843756] 'range keys from in-memory index tree' (duration: 469.156738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:24.673056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:24.203522Z","time spent":"469.525768ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T06:23:27.742381Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609917616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T06:23:28.243581Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609917616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T06:23:28.743791Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609917616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T06:23:29.244851Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609917616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T06:23:29.745727Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609917616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T06:23:30.203502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999950968s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-24T06:23:30.20362Z","caller":"traceutil/trace.go:171","msg":"trace[479239251] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000087001s","start":"2026-02-24T06:23:28.203513Z","end":"2026-02-24T06:23:30.2036Z","steps":["trace[479239251] 'agreement among raft nodes before linearized reading' (duration: 1.999939268s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:30.203722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:28.203449Z","time spent":"2.000260165s","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T06:23:30.246612Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609917616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T06:23:30.747167Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609917616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T06:23:31.248211Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609917616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T06:23:31.749318Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609917616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T06:23:32.204429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999958827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-24T06:23:32.204544Z","caller":"traceutil/trace.go:171","msg":"trace[956771753] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000132461s","start":"2026-02-24T06:23:30.204387Z","end":"2026-02-24T06:23:32.204519Z","steps":["trace[956771753] 'agreement among raft nodes before linearized reading' (duration: 1.999984998s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:32.204597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:30.20437Z","time spent":"2.000216914s","remote":"127.0.0.1:46410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T06:23:32.249823Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609917616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T06:23:32.71978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999430926s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-24T06:23:32.719874Z","caller":"traceutil/trace.go:171","msg":"trace[687097300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.99957994s","start":"2026-02-24T06:23:27.720272Z","end":"2026-02-24T06:23:32.719852Z","steps":["trace[687097300] 'agreement among raft nodes before linearized reading' (duration: 4.999463337s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:32.71994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:27.720252Z","time spent":"4.999678393s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/24 06:23:32 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T06:23:32.724288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999738545s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-24T06:23:32.724408Z","caller":"traceutil/trace.go:171","msg":"trace[1410389440] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.99992377s","start":"2026-02-24T06:23:27.724454Z","end":"2026-02-24T06:23:32.724377Z","steps":["trace[1410389440] 'agreement among raft nodes before linearized reading' (duration: 4.999768276s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:32.724477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:27.724435Z","time spent":"5.000023502s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/02/24 06:23:32 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T06:23:32.750699Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609917616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T06:23:33.251165Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609917616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T06:23:33.419067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000134812s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-24T06:23:33.419181Z","caller":"traceutil/trace.go:171","msg":"trace[1996445930] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000313756s","start":"2026-02-24T06:23:31.418839Z","end":"2026-02-24T06:23:33.419153Z","steps":["trace[1996445930] 'agreement among raft nodes before linearized reading' (duration: 2.000162792s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:33.419242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:31.418813Z","time spent":"2.000418609s","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T06:23:33.751951Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609917616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T06:23:33.931081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:26.930444Z","time spent":"7.000627235s","remote":"127.0.0.1:46484","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-24T06:23:33.937529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:26.937339Z","time spent":"7.000181145s","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-24T06:23:34.213924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00003632s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-24T06:23:34.214011Z","caller":"traceutil/trace.go:171","msg":"trace[837150385] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000190913s","start":"2026-02-24T06:23:32.213799Z","end":"2026-02-24T06:23:34.21399Z","steps":["trace[837150385] 'agreement among raft nodes before linearized reading' (duration: 2.00007945s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:34.214066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:32.213731Z","time spent":"2.000328177s","remote":"127.0.0.1:46410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/24 06:23:34 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T06:23:34.242684Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-02-24T06:23:34.242791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.000972293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-24T06:23:34.242823Z","caller":"traceutil/trace.go:171","msg":"trace[1696761662] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; }","duration":"7.001076516s","start":"2026-02-24T06:23:27.241735Z","end":"2026-02-24T06:23:34.242812Z","steps":["trace[1696761662] 'agreement among raft nodes before linearized reading' (duration: 7.001039075s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:34.242854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:27.241715Z","time spent":"7.001132467s","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":0,"request content":"key:\"/registry/masterleases/162.253.55.36\" "} {"level":"warn","ts":"2026-02-24T06:23:34.742922Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609917622,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T06:23:35.243284Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609917622,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T06:23:35.74371Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609917622,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T06:23:36.222208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999880026s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-24T06:23:36.222296Z","caller":"traceutil/trace.go:171","msg":"trace[409736968] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000013539s","start":"2026-02-24T06:23:34.222263Z","end":"2026-02-24T06:23:36.222277Z","steps":["trace[409736968] 'agreement among raft nodes before linearized reading' (duration: 1.999908766s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:36.222342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:34.222247Z","time spent":"2.000086181s","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T06:23:36.244252Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609917622,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T06:23:36.5473Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"9.616760107s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-24T06:23:36.935654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:29.934549Z","time spent":"7.001098408s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-24T06:23:37.395455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.874146ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-24T06:23:37.39585Z","caller":"traceutil/trace.go:171","msg":"trace[534914068] transaction","detail":"{read_only:false; number_of_response:1; response_revision:549; }","duration":"456.166664ms","start":"2026-02-24T06:23:36.939658Z","end":"2026-02-24T06:23:37.395824Z","steps":["trace[534914068] 'process raft request' (duration: 456.118783ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:23:37.395875Z","caller":"traceutil/trace.go:171","msg":"trace[1439763913] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:565; }","duration":"3.153055016s","start":"2026-02-24T06:23:34.242752Z","end":"2026-02-24T06:23:37.395807Z","steps":["trace[1439763913] 'read index received' (duration: 2.304772842s)","trace[1439763913] 'applied index is now lower than readState.Index' (duration: 848.280404ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:23:37.395997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:36.939635Z","time spent":"456.275486ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":571,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:23:37.395852Z","caller":"traceutil/trace.go:171","msg":"trace[1602024986] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"3.457987142s","start":"2026-02-24T06:23:33.937825Z","end":"2026-02-24T06:23:37.395812Z","steps":["trace[1602024986] 'process raft request' (duration: 3.457750236s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:37.396266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:33.937802Z","time spent":"3.458388513s","remote":"127.0.0.1:46484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T06:23:37.39658Z","caller":"traceutil/trace.go:171","msg":"trace[1936052722] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"628.320028ms","start":"2026-02-24T06:23:36.768228Z","end":"2026-02-24T06:23:37.396548Z","steps":["trace[1936052722] 'process raft request' (duration: 627.482707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:37.396666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.672371141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-24T06:23:37.396693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:36.768198Z","time spent":"628.436201ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:23:37.396716Z","caller":"traceutil/trace.go:171","msg":"trace[1149806906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:549; }","duration":"9.672470873s","start":"2026-02-24T06:23:27.724229Z","end":"2026-02-24T06:23:37.3967Z","steps":["trace[1149806906] 'agreement among raft nodes before linearized reading' (duration: 9.672311279s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:37.396728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.807335556s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-24T06:23:37.396732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.455030972s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xdgqs\" ","response":"range_response_count:1 size:17167"} {"level":"warn","ts":"2026-02-24T06:23:37.396753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:27.724196Z","time spent":"9.672549385s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T06:23:37.396781Z","caller":"traceutil/trace.go:171","msg":"trace[687090271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:549; }","duration":"9.807429208s","start":"2026-02-24T06:23:27.589336Z","end":"2026-02-24T06:23:37.396765Z","steps":["trace[687090271] 'agreement among raft nodes before linearized reading' (duration: 9.807289795s)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:23:37.396793Z","caller":"traceutil/trace.go:171","msg":"trace[806630057] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xdgqs; range_end:; response_count:1; response_revision:549; }","duration":"3.455135205s","start":"2026-02-24T06:23:33.941642Z","end":"2026-02-24T06:23:37.396777Z","steps":["trace[806630057] 'agreement among raft nodes before linearized reading' (duration: 3.454917799s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:37.396826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:27.589316Z","time spent":"9.80749923s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T06:23:37.396831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:33.941623Z","time spent":"3.455198206s","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17191,"request content":"key:\"/registry/pods/kube-system/cilium-xdgqs\" "} {"level":"warn","ts":"2026-02-24T06:23:37.397096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174414502s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T06:23:37.397111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.674656905s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T06:23:37.397141Z","caller":"traceutil/trace.go:171","msg":"trace[80491450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"1.174489674s","start":"2026-02-24T06:23:36.222638Z","end":"2026-02-24T06:23:37.397127Z","steps":["trace[80491450] 'agreement among raft nodes before linearized reading' (duration: 1.174422662s)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:23:37.397171Z","caller":"traceutil/trace.go:171","msg":"trace[321118070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:549; }","duration":"2.674749667s","start":"2026-02-24T06:23:34.722402Z","end":"2026-02-24T06:23:37.397152Z","steps":["trace[321118070] 'agreement among raft nodes before linearized reading' (duration: 2.674584014s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:37.397189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:36.222621Z","time spent":"1.174557625s","remote":"127.0.0.1:46410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T06:23:37.39721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.02104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T06:23:37.397215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:34.722382Z","time spent":"2.67482179s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T06:23:37.397249Z","caller":"traceutil/trace.go:171","msg":"trace[1498697404] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; response_count:0; response_revision:549; }","duration":"155.115222ms","start":"2026-02-24T06:23:37.242122Z","end":"2026-02-24T06:23:37.397237Z","steps":["trace[1498697404] 'agreement among raft nodes before linearized reading' (duration: 155.0526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:37.397263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.671291034s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T06:23:37.397312Z","caller":"traceutil/trace.go:171","msg":"trace[1759316659] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:549; }","duration":"2.671378976s","start":"2026-02-24T06:23:34.725918Z","end":"2026-02-24T06:23:37.397297Z","steps":["trace[1759316659] 'agreement among raft nodes before linearized reading' (duration: 2.671252043s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:37.397352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:34.725898Z","time spent":"2.671443378s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/02/24 06:23:37 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T06:23:37.720357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:37.401398Z","time spent":"318.952044ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/24 06:23:37 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T06:23:37.724333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:37.405305Z","time spent":"319.024197ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/24 06:23:37 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T06:23:37.725168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:37.401427Z","time spent":"323.73029ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/24 06:23:37 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T06:23:38.114094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.573973ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-02-24T06:23:38.114193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:37.399072Z","time spent":"715.118102ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-24T06:23:38.823275Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.20167083s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T06:23:38.843667Z","caller":"traceutil/trace.go:171","msg":"trace[197954522] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"1.443512264s","start":"2026-02-24T06:23:37.400119Z","end":"2026-02-24T06:23:38.843631Z","steps":["trace[197954522] 'process raft request' (duration: 1.423401959s)","trace[197954522] 'compare' (duration: 19.664594ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:23:38.843741Z","caller":"traceutil/trace.go:171","msg":"trace[2128466132] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:572; }","duration":"1.442336366s","start":"2026-02-24T06:23:37.401382Z","end":"2026-02-24T06:23:38.843718Z","steps":["trace[2128466132] 'read index received' (duration: 220.198452ms)","trace[2128466132] 'applied index is now lower than readState.Index' (duration: 1.222137034s)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:23:38.843813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:37.400101Z","time spent":"1.443632866s","remote":"127.0.0.1:46484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T06:23:38.843978Z","caller":"traceutil/trace.go:171","msg":"trace[906324254] transaction","detail":"{read_only:false; number_of_response:1; response_revision:554; }","duration":"1.251594473s","start":"2026-02-24T06:23:37.59235Z","end":"2026-02-24T06:23:38.843945Z","steps":["trace[906324254] 'process raft request' (duration: 1.251339337s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:38.844126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.442718995s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"warn","ts":"2026-02-24T06:23:38.844188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:37.59224Z","time spent":"1.25182583s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":46,"response count":0,"response size":469,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:23:38.844205Z","caller":"traceutil/trace.go:171","msg":"trace[641282646] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:554; }","duration":"1.442834487s","start":"2026-02-24T06:23:37.401353Z","end":"2026-02-24T06:23:38.844188Z","steps":["trace[641282646] 'agreement among raft nodes before linearized reading' (duration: 1.442676643s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:38.844451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:37.401343Z","time spent":"1.443061723s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":560,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-02-24T06:23:38.851046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:37.727062Z","time spent":"1.123980585s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/24 06:23:38 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-24T06:23:38.856496Z","caller":"traceutil/trace.go:171","msg":"trace[1701743155] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"741.188851ms","start":"2026-02-24T06:23:38.115291Z","end":"2026-02-24T06:23:38.85648Z","steps":["trace[1701743155] 'process raft request' (duration: 741.13097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:38.856632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.451468596s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:23:38.8567Z","caller":"traceutil/trace.go:171","msg":"trace[246749982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"1.451570828s","start":"2026-02-24T06:23:37.405114Z","end":"2026-02-24T06:23:38.856684Z","steps":["trace[246749982] 'agreement among raft nodes before linearized reading' (duration: 1.451405314s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:38.856741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:37.405104Z","time spent":"1.451627799s","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T06:23:38.856754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:38.115265Z","time spent":"741.414537ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:23:38.856956Z","caller":"traceutil/trace.go:171","msg":"trace[1099279403] transaction","detail":"{read_only:false; number_of_response:1; response_revision:554; }","duration":"1.133744831s","start":"2026-02-24T06:23:37.723202Z","end":"2026-02-24T06:23:38.856947Z","steps":["trace[1099279403] 'process raft request' (duration: 1.133146107s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:38.856984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.454744275s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7036"} {"level":"warn","ts":"2026-02-24T06:23:38.857008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:37.723169Z","time spent":"1.133815873s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":47,"response count":0,"response size":514,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:23:38.857025Z","caller":"traceutil/trace.go:171","msg":"trace[1162312096] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:555; }","duration":"1.454800026s","start":"2026-02-24T06:23:37.402215Z","end":"2026-02-24T06:23:38.857015Z","steps":["trace[1162312096] 'agreement among raft nodes before linearized reading' (duration: 1.454723194s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:38.857057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:37.402208Z","time spent":"1.454842217s","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7060,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-02-24T06:23:38.991234Z","caller":"traceutil/trace.go:171","msg":"trace[2114758497] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"124.556976ms","start":"2026-02-24T06:23:38.866637Z","end":"2026-02-24T06:23:38.991194Z","steps":["trace[2114758497] 'process raft request' (duration: 124.31888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:38.991284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.277191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-24T06:23:38.991393Z","caller":"traceutil/trace.go:171","msg":"trace[2096149155] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:557; }","duration":"132.426824ms","start":"2026-02-24T06:23:38.858941Z","end":"2026-02-24T06:23:38.991368Z","steps":["trace[2096149155] 'agreement among raft nodes before linearized reading' (duration: 132.222259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:38.991766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.700871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:23:38.991836Z","caller":"traceutil/trace.go:171","msg":"trace[1339045774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"132.817784ms","start":"2026-02-24T06:23:38.859001Z","end":"2026-02-24T06:23:38.991819Z","steps":["trace[1339045774] 'agreement among raft nodes before linearized reading' (duration: 132.692801ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:23:39.2679Z","caller":"traceutil/trace.go:171","msg":"trace[12519361] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"163.677659ms","start":"2026-02-24T06:23:39.1042Z","end":"2026-02-24T06:23:39.267878Z","steps":["trace[12519361] 'process raft request' (duration: 117.550536ms)","trace[12519361] 'compare' (duration: 46.035281ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:23:39.272206Z","caller":"traceutil/trace.go:171","msg":"trace[370910851] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"162.745016ms","start":"2026-02-24T06:23:39.109435Z","end":"2026-02-24T06:23:39.27218Z","steps":["trace[370910851] 'process raft request' (duration: 162.587552ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:23:39.722664Z","caller":"traceutil/trace.go:171","msg":"trace[573382116] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"105.625589ms","start":"2026-02-24T06:23:39.617019Z","end":"2026-02-24T06:23:39.722645Z","steps":["trace[573382116] 'process raft request' (duration: 105.499476ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:23:40.211007Z","caller":"traceutil/trace.go:171","msg":"trace[858840724] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"143.387539ms","start":"2026-02-24T06:23:40.067598Z","end":"2026-02-24T06:23:40.210986Z","steps":["trace[858840724] 'process raft request' (duration: 143.293257ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:23:42.343271Z","caller":"traceutil/trace.go:171","msg":"trace[1523161594] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"136.852641ms","start":"2026-02-24T06:23:42.206391Z","end":"2026-02-24T06:23:42.343244Z","steps":["trace[1523161594] 'read index received' (duration: 136.631696ms)","trace[1523161594] 'applied index is now lower than readState.Index' (duration: 219.705µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:23:42.343475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.052747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:23:42.343571Z","caller":"traceutil/trace.go:171","msg":"trace[234816835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"137.161499ms","start":"2026-02-24T06:23:42.206385Z","end":"2026-02-24T06:23:42.343546Z","steps":["trace[234816835] 'agreement among raft nodes before linearized reading' (duration: 136.960213ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:23:42.343901Z","caller":"traceutil/trace.go:171","msg":"trace[2090016288] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"174.759926ms","start":"2026-02-24T06:23:42.16912Z","end":"2026-02-24T06:23:42.34388Z","steps":["trace[2090016288] 'process raft request' (duration: 173.956476ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:23:45.444751Z","caller":"traceutil/trace.go:171","msg":"trace[240568005] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"100.313854ms","start":"2026-02-24T06:23:45.34442Z","end":"2026-02-24T06:23:45.444734Z","steps":["trace[240568005] 'read index received' (duration: 90.596731ms)","trace[240568005] 'applied index is now lower than readState.Index' (duration: 9.716473ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:23:45.444797Z","caller":"traceutil/trace.go:171","msg":"trace[904399780] transaction","detail":"{read_only:false; number_of_response:0; response_revision:650; }","duration":"189.228795ms","start":"2026-02-24T06:23:45.25554Z","end":"2026-02-24T06:23:45.444769Z","steps":["trace[904399780] 'process raft request' (duration: 179.554303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:45.444871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.417998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:23:45.444911Z","caller":"traceutil/trace.go:171","msg":"trace[280088281] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:650; }","duration":"100.519379ms","start":"2026-02-24T06:23:45.34438Z","end":"2026-02-24T06:23:45.4449Z","steps":["trace[280088281] 'agreement among raft nodes before linearized reading' (duration: 100.428547ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:23:48.021384Z","caller":"traceutil/trace.go:171","msg":"trace[535625726] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"216.166746ms","start":"2026-02-24T06:23:47.8052Z","end":"2026-02-24T06:23:48.021367Z","steps":["trace[535625726] 'process raft request' (duration: 216.053233ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:23:48.222801Z","caller":"traceutil/trace.go:171","msg":"trace[740732101] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"165.008599ms","start":"2026-02-24T06:23:48.05776Z","end":"2026-02-24T06:23:48.222768Z","steps":["trace[740732101] 'process raft request' (duration: 135.770179ms)","trace[740732101] 'compare' (duration: 28.977383ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:23:54.696337Z","caller":"traceutil/trace.go:171","msg":"trace[600528054] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:707; }","duration":"108.512099ms","start":"2026-02-24T06:23:54.587804Z","end":"2026-02-24T06:23:54.696316Z","steps":["trace[600528054] 'read index received' (duration: 108.356435ms)","trace[600528054] 'applied index is now lower than readState.Index' (duration: 154.784µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:23:54.696464Z","caller":"traceutil/trace.go:171","msg":"trace[1156168097] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"180.605298ms","start":"2026-02-24T06:23:54.515811Z","end":"2026-02-24T06:23:54.696416Z","steps":["trace[1156168097] 'process raft request' (duration: 180.374013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:54.696632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.811417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T06:23:54.696724Z","caller":"traceutil/trace.go:171","msg":"trace[1363114379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:677; }","duration":"108.95638ms","start":"2026-02-24T06:23:54.587745Z","end":"2026-02-24T06:23:54.696701Z","steps":["trace[1363114379] 'agreement among raft nodes before linearized reading' (duration: 108.700324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:55.209957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.791464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T06:23:55.210114Z","caller":"traceutil/trace.go:171","msg":"trace[877968469] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"510.004531ms","start":"2026-02-24T06:23:54.700092Z","end":"2026-02-24T06:23:55.210097Z","steps":["trace[877968469] 'process raft request' (duration: 338.800208ms)","trace[877968469] 'compare' (duration: 170.68176ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:23:55.210171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:54.700072Z","time spent":"510.072074ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:23:55.210292Z","caller":"traceutil/trace.go:171","msg":"trace[814602740] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"509.713395ms","start":"2026-02-24T06:23:54.700558Z","end":"2026-02-24T06:23:55.210272Z","steps":["trace[814602740] 'process raft request' (duration: 509.478938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:23:55.210403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:54.700541Z","time spent":"509.808436ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:23:59.650767Z","caller":"traceutil/trace.go:171","msg":"trace[1761594628] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:732; }","duration":"144.629521ms","start":"2026-02-24T06:23:59.506112Z","end":"2026-02-24T06:23:59.650741Z","steps":["trace[1761594628] 'read index received' (duration: 125.143364ms)","trace[1761594628] 'applied index is now lower than readState.Index' (duration: 19.485197ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:23:59.650862Z","caller":"traceutil/trace.go:171","msg":"trace[1015208500] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"169.372138ms","start":"2026-02-24T06:23:59.481461Z","end":"2026-02-24T06:23:59.650834Z","steps":["trace[1015208500] 'process raft request' (duration: 149.889592ms)","trace[1015208500] 'compare' (duration: 19.263391ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:23:59.651066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.861036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-24T06:23:59.651121Z","caller":"traceutil/trace.go:171","msg":"trace[995509185] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:700; }","duration":"145.061872ms","start":"2026-02-24T06:23:59.506047Z","end":"2026-02-24T06:23:59.651109Z","steps":["trace[995509185] 'agreement among raft nodes before linearized reading' (duration: 144.865157ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:00.023598Z","caller":"traceutil/trace.go:171","msg":"trace[1545177497] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"361.863043ms","start":"2026-02-24T06:23:59.661711Z","end":"2026-02-24T06:24:00.023574Z","steps":["trace[1545177497] 'process raft request' (duration: 361.672759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:00.023761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:59.661689Z","time spent":"361.987317ms","remote":"127.0.0.1:46886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4365,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:24:00.415932Z","caller":"traceutil/trace.go:171","msg":"trace[1259892255] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:733; }","duration":"436.067626ms","start":"2026-02-24T06:23:59.979838Z","end":"2026-02-24T06:24:00.415905Z","steps":["trace[1259892255] 'read index received' (duration: 43.525257ms)","trace[1259892255] 'applied index is now lower than readState.Index' (duration: 392.541059ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:24:00.415979Z","caller":"traceutil/trace.go:171","msg":"trace[1245461584] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"753.831438ms","start":"2026-02-24T06:23:59.662112Z","end":"2026-02-24T06:24:00.415943Z","steps":["trace[1245461584] 'process raft request' (duration: 753.50928ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:00.41599Z","caller":"traceutil/trace.go:171","msg":"trace[2034095113] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"750.363213ms","start":"2026-02-24T06:23:59.665591Z","end":"2026-02-24T06:24:00.415954Z","steps":["trace[2034095113] 'process raft request' (duration: 750.229089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:00.416087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:59.662093Z","time spent":"753.941222ms","remote":"127.0.0.1:46484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T06:24:00.416168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.307062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/kube-dns\" ","response":"range_response_count:1 size:1211"} {"level":"warn","ts":"2026-02-24T06:24:00.416181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:59.665584Z","time spent":"750.503116ms","remote":"127.0.0.1:46602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4727,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:24:00.416216Z","caller":"traceutil/trace.go:171","msg":"trace[1477639377] range","detail":"{range_begin:/registry/services/specs/kube-system/kube-dns; range_end:; response_count:1; response_revision:703; }","duration":"436.413415ms","start":"2026-02-24T06:23:59.979789Z","end":"2026-02-24T06:24:00.416202Z","steps":["trace[1477639377] 'agreement among raft nodes before linearized reading' (duration: 436.274341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:00.416258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:59.979767Z","time spent":"436.481127ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1235,"request content":"key:\"/registry/services/specs/kube-system/kube-dns\" "} {"level":"warn","ts":"2026-02-24T06:24:00.416467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.540998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3797"} {"level":"info","ts":"2026-02-24T06:24:00.4165Z","caller":"traceutil/trace.go:171","msg":"trace[1880090104] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:703; }","duration":"436.594229ms","start":"2026-02-24T06:23:59.979896Z","end":"2026-02-24T06:24:00.41649Z","steps":["trace[1880090104] 'agreement among raft nodes before linearized reading' (duration: 436.513997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:00.416525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:59.979888Z","time spent":"436.632921ms","remote":"127.0.0.1:46886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3821,"request content":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" "} {"level":"warn","ts":"2026-02-24T06:24:00.416647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.629571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" ","response":"range_response_count:1 size:3833"} {"level":"info","ts":"2026-02-24T06:24:00.416664Z","caller":"traceutil/trace.go:171","msg":"trace[1155847824] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-77cccfdc44; range_end:; response_count:1; response_revision:703; }","duration":"436.897477ms","start":"2026-02-24T06:23:59.979761Z","end":"2026-02-24T06:24:00.416658Z","steps":["trace[1155847824] 'agreement among raft nodes before linearized reading' (duration: 436.864356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:00.416721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:59.979683Z","time spent":"437.03094ms","remote":"127.0.0.1:46886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3857,"request content":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" "} {"level":"warn","ts":"2026-02-24T06:24:00.416763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.645071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14326"} {"level":"warn","ts":"2026-02-24T06:24:00.416808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.965531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:24:00.416837Z","caller":"traceutil/trace.go:171","msg":"trace[1384140205] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:703; }","duration":"436.767904ms","start":"2026-02-24T06:23:59.980049Z","end":"2026-02-24T06:24:00.416817Z","steps":["trace[1384140205] 'agreement among raft nodes before linearized reading' (duration: 436.529728ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:00.416858Z","caller":"traceutil/trace.go:171","msg":"trace[1979327205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:703; }","duration":"210.067164ms","start":"2026-02-24T06:24:00.206774Z","end":"2026-02-24T06:24:00.416841Z","steps":["trace[1979327205] 'agreement among raft nodes before linearized reading' (duration: 209.990973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:00.416878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:59.980024Z","time spent":"436.845975ms","remote":"127.0.0.1:46880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":14350,"request content":"key:\"/registry/daemonsets/kube-system/cilium\" "} {"level":"warn","ts":"2026-02-24T06:24:00.416877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.895956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/cilium-operator-7fcd8d6ffd\" ","response":"range_response_count:1 size:4380"} {"level":"warn","ts":"2026-02-24T06:24:00.416954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.984169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2896"} {"level":"info","ts":"2026-02-24T06:24:00.416961Z","caller":"traceutil/trace.go:171","msg":"trace[883171754] range","detail":"{range_begin:/registry/replicasets/kube-system/cilium-operator-7fcd8d6ffd; range_end:; response_count:1; response_revision:703; }","duration":"437.056261ms","start":"2026-02-24T06:23:59.979885Z","end":"2026-02-24T06:24:00.416941Z","steps":["trace[883171754] 'agreement among raft nodes before linearized reading' (duration: 436.773454ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:00.417013Z","caller":"traceutil/trace.go:171","msg":"trace[996348106] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:703; }","duration":"437.093561ms","start":"2026-02-24T06:23:59.979905Z","end":"2026-02-24T06:24:00.416998Z","steps":["trace[996348106] 'agreement among raft nodes before linearized reading' (duration: 436.953148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:00.417031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:59.979877Z","time spent":"437.144663ms","remote":"127.0.0.1:46886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":4404,"request content":"key:\"/registry/replicasets/kube-system/cilium-operator-7fcd8d6ffd\" "} {"level":"warn","ts":"2026-02-24T06:24:00.417044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.075048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-24T06:24:00.416969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.315813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-24T06:24:00.417056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:23:59.979888Z","time spent":"437.156503ms","remote":"127.0.0.1:46880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":2920,"request content":"key:\"/registry/daemonsets/kube-system/kube-proxy\" "} {"level":"info","ts":"2026-02-24T06:24:00.41709Z","caller":"traceutil/trace.go:171","msg":"trace[812826450] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:703; }","duration":"388.449786ms","start":"2026-02-24T06:24:00.028633Z","end":"2026-02-24T06:24:00.417083Z","steps":["trace[812826450] 'agreement among raft nodes before linearized reading' (duration: 388.302973ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:00.417094Z","caller":"traceutil/trace.go:171","msg":"trace[1722590061] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:703; }","duration":"104.17375ms","start":"2026-02-24T06:24:00.312905Z","end":"2026-02-24T06:24:00.417079Z","steps":["trace[1722590061] 'agreement among raft nodes before linearized reading' (duration: 104.005826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:00.417111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:00.028624Z","time spent":"388.481697ms","remote":"127.0.0.1:46624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-02-24T06:24:00.545233Z","caller":"traceutil/trace.go:171","msg":"trace[762589678] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:736; }","duration":"122.424497ms","start":"2026-02-24T06:24:00.422784Z","end":"2026-02-24T06:24:00.545209Z","steps":["trace[762589678] 'read index received' (duration: 122.357385ms)","trace[762589678] 'applied index is now lower than readState.Index' (duration: 66.042µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:24:00.545256Z","caller":"traceutil/trace.go:171","msg":"trace[1140767617] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"123.183155ms","start":"2026-02-24T06:24:00.422044Z","end":"2026-02-24T06:24:00.545227Z","steps":["trace[1140767617] 'process raft request' (duration: 123.025872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:00.545451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.644852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-24T06:24:00.545523Z","caller":"traceutil/trace.go:171","msg":"trace[1628164310] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:704; }","duration":"122.756904ms","start":"2026-02-24T06:24:00.422752Z","end":"2026-02-24T06:24:00.545508Z","steps":["trace[1628164310] 'agreement among raft nodes before linearized reading' (duration: 122.5708ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:00.827501Z","caller":"traceutil/trace.go:171","msg":"trace[1208331687] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"404.371666ms","start":"2026-02-24T06:24:00.423103Z","end":"2026-02-24T06:24:00.827475Z","steps":["trace[1208331687] 'process raft request' (duration: 404.163121ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:00.827772Z","caller":"traceutil/trace.go:171","msg":"trace[593844915] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"404.443837ms","start":"2026-02-24T06:24:00.423304Z","end":"2026-02-24T06:24:00.827747Z","steps":["trace[593844915] 'process raft request' (duration: 404.112919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:00.829501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:00.423296Z","time spent":"406.134339ms","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3834,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:24:00.829547Z","caller":"traceutil/trace.go:171","msg":"trace[61110187] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"406.124248ms","start":"2026-02-24T06:24:00.423398Z","end":"2026-02-24T06:24:00.829522Z","steps":["trace[61110187] 'process raft request' (duration: 404.251312ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:00.829582Z","caller":"traceutil/trace.go:171","msg":"trace[717060574] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:737; }","duration":"284.189864ms","start":"2026-02-24T06:24:00.5453Z","end":"2026-02-24T06:24:00.82949Z","steps":["trace[717060574] 'read index received' (duration: 282.047182ms)","trace[717060574] 'applied index is now lower than readState.Index' (duration: 2.141082ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:24:00.829744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:00.423393Z","time spent":"406.242592ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:24:00.829844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:00.423093Z","time spent":"404.515919ms","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3834,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:24:00.964814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.9455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4173"} {"level":"warn","ts":"2026-02-24T06:24:00.964814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.799386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4173"} {"level":"info","ts":"2026-02-24T06:24:00.964906Z","caller":"traceutil/trace.go:171","msg":"trace[782320988] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:707; }","duration":"542.044552ms","start":"2026-02-24T06:24:00.422835Z","end":"2026-02-24T06:24:00.96488Z","steps":["trace[782320988] 'agreement among raft nodes before linearized reading' (duration: 406.871678ms)","trace[782320988] 'range keys from in-memory index tree' (duration: 134.721993ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:24:00.964913Z","caller":"traceutil/trace.go:171","msg":"trace[2077663638] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:707; }","duration":"541.931149ms","start":"2026-02-24T06:24:00.422964Z","end":"2026-02-24T06:24:00.964895Z","steps":["trace[2077663638] 'agreement among raft nodes before linearized reading' (duration: 406.60177ms)","trace[2077663638] 'range keys from in-memory index tree' (duration: 135.136894ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:24:00.964954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:00.422959Z","time spent":"541.985331ms","remote":"127.0.0.1:46866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4197,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-24T06:24:00.964958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:00.422831Z","time spent":"542.117984ms","remote":"127.0.0.1:46866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4197,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-24T06:24:00.96557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.647467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4649"} {"level":"info","ts":"2026-02-24T06:24:00.965628Z","caller":"traceutil/trace.go:171","msg":"trace[1212588544] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:707; }","duration":"542.727669ms","start":"2026-02-24T06:24:00.422886Z","end":"2026-02-24T06:24:00.965614Z","steps":["trace[1212588544] 'agreement among raft nodes before linearized reading' (duration: 406.718343ms)","trace[1212588544] 'range keys from in-memory index tree' (duration: 135.923534ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:24:00.965659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:00.422878Z","time spent":"542.77391ms","remote":"127.0.0.1:46866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":4673,"request content":"key:\"/registry/deployments/kube-system/cilium-operator\" "} {"level":"info","ts":"2026-02-24T06:24:00.970862Z","caller":"traceutil/trace.go:171","msg":"trace[841673833] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"141.204406ms","start":"2026-02-24T06:24:00.829644Z","end":"2026-02-24T06:24:00.970849Z","steps":["trace[841673833] 'read index received' (duration: 141.159155ms)","trace[841673833] 'applied index is now lower than readState.Index' (duration: 44.521µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:24:00.970894Z","caller":"traceutil/trace.go:171","msg":"trace[1578549124] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"420.891687ms","start":"2026-02-24T06:24:00.549962Z","end":"2026-02-24T06:24:00.970854Z","steps":["trace[1578549124] 'process raft request' (duration: 420.698222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:00.971022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:00.549929Z","time spent":"421.02845ms","remote":"127.0.0.1:46928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":708,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T06:24:00.971035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.19645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-8bmmm\" ","response":"range_response_count:1 size:3813"} {"level":"info","ts":"2026-02-24T06:24:00.97112Z","caller":"traceutil/trace.go:171","msg":"trace[2088281854] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-8bmmm; range_end:; response_count:1; response_revision:709; }","duration":"420.281441ms","start":"2026-02-24T06:24:00.550793Z","end":"2026-02-24T06:24:00.971074Z","steps":["trace[2088281854] 'agreement among raft nodes before linearized reading' (duration: 420.114207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:00.97117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:00.550778Z","time spent":"420.380104ms","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":3837,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-8bmmm\" "} {"level":"warn","ts":"2026-02-24T06:24:00.971421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.818516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-24T06:24:00.971434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.498262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T06:24:00.971458Z","caller":"traceutil/trace.go:171","msg":"trace[427858357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:709; }","duration":"255.888247ms","start":"2026-02-24T06:24:00.715561Z","end":"2026-02-24T06:24:00.971449Z","steps":["trace[427858357] 'agreement among raft nodes before linearized reading' (duration: 255.787905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:00.971462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.207364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-24T06:24:00.971477Z","caller":"traceutil/trace.go:171","msg":"trace[1907378444] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:709; }","duration":"145.543203ms","start":"2026-02-24T06:24:00.825922Z","end":"2026-02-24T06:24:00.971465Z","steps":["trace[1907378444] 'agreement among raft nodes before linearized reading' (duration: 145.402879ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:00.971476Z","caller":"traceutil/trace.go:171","msg":"trace[593097178] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"416.371865ms","start":"2026-02-24T06:24:00.555084Z","end":"2026-02-24T06:24:00.971455Z","steps":["trace[593097178] 'process raft request' (duration: 415.704028ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:00.971538Z","caller":"traceutil/trace.go:171","msg":"trace[179395748] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:709; }","duration":"286.319227ms","start":"2026-02-24T06:24:00.685205Z","end":"2026-02-24T06:24:00.971524Z","steps":["trace[179395748] 'agreement among raft nodes before linearized reading' (duration: 286.176734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:00.971621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:00.555075Z","time spent":"416.474077ms","remote":"127.0.0.1:46866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:24:00.97631Z","caller":"traceutil/trace.go:171","msg":"trace[2140588816] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"140.979719ms","start":"2026-02-24T06:24:00.835313Z","end":"2026-02-24T06:24:00.976293Z","steps":["trace[2140588816] 'process raft request' (duration: 140.852856ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:00.976332Z","caller":"traceutil/trace.go:171","msg":"trace[605803394] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"139.500503ms","start":"2026-02-24T06:24:00.836808Z","end":"2026-02-24T06:24:00.976309Z","steps":["trace[605803394] 'process raft request' (duration: 139.402021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:01.27459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.271004ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T06:24:01.274856Z","caller":"traceutil/trace.go:171","msg":"trace[505913759] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"299.823705ms","start":"2026-02-24T06:24:00.975014Z","end":"2026-02-24T06:24:01.274838Z","steps":["trace[505913759] 'process raft request' (duration: 94.230563ms)","trace[505913759] 'compare' (duration: 205.143781ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:24:01.27498Z","caller":"traceutil/trace.go:171","msg":"trace[1806168633] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"298.903501ms","start":"2026-02-24T06:24:00.976053Z","end":"2026-02-24T06:24:01.274956Z","steps":["trace[1806168633] 'process raft request' (duration: 298.745617ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:01.312915Z","caller":"traceutil/trace.go:171","msg":"trace[92711441] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:744; }","duration":"324.917041ms","start":"2026-02-24T06:24:00.987982Z","end":"2026-02-24T06:24:01.312899Z","steps":["trace[92711441] 'read index received' (duration: 81.364512ms)","trace[92711441] 'applied index is now lower than readState.Index' (duration: 243.551879ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:24:01.312998Z","caller":"traceutil/trace.go:171","msg":"trace[884028334] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"332.147972ms","start":"2026-02-24T06:24:00.980843Z","end":"2026-02-24T06:24:01.312991Z","steps":["trace[884028334] 'process raft request' (duration: 331.978697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:01.313071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:00.980833Z","time spent":"332.180193ms","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4681,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:24:01.313184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.200708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-24T06:24:01.313211Z","caller":"traceutil/trace.go:171","msg":"trace[1929391950] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:714; }","duration":"325.249399ms","start":"2026-02-24T06:24:00.987955Z","end":"2026-02-24T06:24:01.313204Z","steps":["trace[1929391950] 'agreement among raft nodes before linearized reading' (duration: 325.192507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:01.31323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:00.987945Z","time spent":"325.28086ms","remote":"127.0.0.1:46624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":203,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-24T06:24:01.313388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.644054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-24T06:24:01.313412Z","caller":"traceutil/trace.go:171","msg":"trace[2142827227] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:714; }","duration":"324.685445ms","start":"2026-02-24T06:24:00.988721Z","end":"2026-02-24T06:24:01.313407Z","steps":["trace[2142827227] 'agreement among raft nodes before linearized reading' (duration: 324.624113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:01.313431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:00.988714Z","time spent":"324.713436ms","remote":"127.0.0.1:46624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":203,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-24T06:24:01.625873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.901347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T06:24:01.625979Z","caller":"traceutil/trace.go:171","msg":"trace[1764403586] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"307.834875ms","start":"2026-02-24T06:24:01.318125Z","end":"2026-02-24T06:24:01.62596Z","steps":["trace[1764403586] 'process raft request' (duration: 142.778714ms)","trace[1764403586] 'compare' (duration: 164.764694ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:24:01.62603Z","caller":"traceutil/trace.go:171","msg":"trace[580491888] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"305.696531ms","start":"2026-02-24T06:24:01.320323Z","end":"2026-02-24T06:24:01.62602Z","steps":["trace[580491888] 'process raft request' (duration: 305.64569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:01.626045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:01.318101Z","time spent":"307.907926ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:24:01.626098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:01.320313Z","time spent":"305.754572ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:24:01.626175Z","caller":"traceutil/trace.go:171","msg":"trace[1201082021] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:747; }","duration":"307.777463ms","start":"2026-02-24T06:24:01.318387Z","end":"2026-02-24T06:24:01.626165Z","steps":["trace[1201082021] 'read index received' (duration: 142.528828ms)","trace[1201082021] 'applied index is now lower than readState.Index' (duration: 165.247595ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:24:01.626377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.981528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-2sgxk\" ","response":"range_response_count:1 size:3849"} {"level":"info","ts":"2026-02-24T06:24:01.626405Z","caller":"traceutil/trace.go:171","msg":"trace[1024755877] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-2sgxk; range_end:; response_count:1; response_revision:716; }","duration":"308.02391ms","start":"2026-02-24T06:24:01.318372Z","end":"2026-02-24T06:24:01.626396Z","steps":["trace[1024755877] 'agreement among raft nodes before linearized reading' (duration: 307.901017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:01.626429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:01.318366Z","time spent":"308.05676ms","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":3873,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-2sgxk\" "} {"level":"warn","ts":"2026-02-24T06:24:01.626435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.922866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:24:01.62648Z","caller":"traceutil/trace.go:171","msg":"trace[1809524655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:716; }","duration":"209.000208ms","start":"2026-02-24T06:24:01.417467Z","end":"2026-02-24T06:24:01.626467Z","steps":["trace[1809524655] 'agreement among raft nodes before linearized reading' (duration: 208.931117ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:02.084781Z","caller":"traceutil/trace.go:171","msg":"trace[1625815985] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"214.068494ms","start":"2026-02-24T06:24:01.870688Z","end":"2026-02-24T06:24:02.084756Z","steps":["trace[1625815985] 'read index received' (duration: 182.778832ms)","trace[1625815985] 'applied index is now lower than readState.Index' (duration: 31.288662ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:24:02.084832Z","caller":"traceutil/trace.go:171","msg":"trace[1309498810] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"450.077286ms","start":"2026-02-24T06:24:01.634721Z","end":"2026-02-24T06:24:02.084798Z","steps":["trace[1309498810] 'process raft request' (duration: 418.754463ms)","trace[1309498810] 'compare' (duration: 31.172338ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:24:02.084948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.242029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T06:24:02.084989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:01.634703Z","time spent":"450.202219ms","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4717,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:24:02.085015Z","caller":"traceutil/trace.go:171","msg":"trace[2118488918] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:717; }","duration":"214.345041ms","start":"2026-02-24T06:24:01.870651Z","end":"2026-02-24T06:24:02.084996Z","steps":["trace[2118488918] 'agreement among raft nodes before linearized reading' (duration: 214.189618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:02.705279Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609918059,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T06:24:02.829677Z","caller":"traceutil/trace.go:171","msg":"trace[892952919] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"735.183712ms","start":"2026-02-24T06:24:02.094471Z","end":"2026-02-24T06:24:02.829654Z","steps":["trace[892952919] 'process raft request' (duration: 735.034538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:02.82984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:02.094454Z","time spent":"735.290645ms","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4717,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:24:03.626535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.496991ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-24T06:24:03.626697Z","caller":"traceutil/trace.go:171","msg":"trace[459229165] linearizableReadLoop","detail":"{readStateIndex:752; appliedIndex:750; }","duration":"1.422488421s","start":"2026-02-24T06:24:02.204196Z","end":"2026-02-24T06:24:03.626684Z","steps":["trace[459229165] 'read index received' (duration: 625.32282ms)","trace[459229165] 'applied index is now lower than readState.Index' (duration: 797.164841ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:24:03.626831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.422613595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:24:03.626922Z","caller":"traceutil/trace.go:171","msg":"trace[1539722120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:718; }","duration":"1.422751938s","start":"2026-02-24T06:24:02.204146Z","end":"2026-02-24T06:24:03.626898Z","steps":["trace[1539722120] 'agreement among raft nodes before linearized reading' (duration: 1.422594914s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:03.626924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.279599704s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T06:24:03.626969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:02.204107Z","time spent":"1.422853341s","remote":"127.0.0.1:46410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T06:24:03.62699Z","caller":"traceutil/trace.go:171","msg":"trace[1921684915] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:719; }","duration":"1.279679246s","start":"2026-02-24T06:24:02.347295Z","end":"2026-02-24T06:24:03.626974Z","steps":["trace[1921684915] 'agreement among raft nodes before linearized reading' (duration: 1.279541753s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:03.627002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.420247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-24T06:24:03.62703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:02.34724Z","time spent":"1.279781429s","remote":"127.0.0.1:46880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-02-24T06:24:03.627055Z","caller":"traceutil/trace.go:171","msg":"trace[126265664] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:719; }","duration":"348.51692ms","start":"2026-02-24T06:24:03.278526Z","end":"2026-02-24T06:24:03.627043Z","steps":["trace[126265664] 'agreement among raft nodes before linearized reading' (duration: 348.360256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:03.627083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:03.278506Z","time spent":"348.572212ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T06:24:03.627219Z","caller":"traceutil/trace.go:171","msg":"trace[796047994] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"395.35486ms","start":"2026-02-24T06:24:03.231854Z","end":"2026-02-24T06:24:03.627209Z","steps":["trace[796047994] 'process raft request' (duration: 394.767345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:03.627265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:03.231832Z","time spent":"395.40205ms","remote":"127.0.0.1:45834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1238,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T06:24:03.627268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.772637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T06:24:03.627354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.366476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-24T06:24:03.627357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.194372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T06:24:03.627367Z","caller":"traceutil/trace.go:171","msg":"trace[1750097799] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:719; }","duration":"473.930881ms","start":"2026-02-24T06:24:03.153413Z","end":"2026-02-24T06:24:03.627344Z","steps":["trace[1750097799] 'agreement among raft nodes before linearized reading' (duration: 473.734146ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:03.627395Z","caller":"traceutil/trace.go:171","msg":"trace[861417881] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:719; }","duration":"789.271974ms","start":"2026-02-24T06:24:02.838113Z","end":"2026-02-24T06:24:03.627385Z","steps":["trace[861417881] 'agreement among raft nodes before linearized reading' (duration: 789.11326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:03.627425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:02.838092Z","time spent":"789.326005ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T06:24:03.627428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:03.153397Z","time spent":"474.014533ms","remote":"127.0.0.1:46758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":13,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-24T06:24:03.627385Z","caller":"traceutil/trace.go:171","msg":"trace[45712741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:719; }","duration":"348.424908ms","start":"2026-02-24T06:24:03.278941Z","end":"2026-02-24T06:24:03.627366Z","steps":["trace[45712741] 'agreement among raft nodes before linearized reading' (duration: 348.367456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:03.62754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:03.27892Z","time spent":"348.602553ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T06:24:04.186777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.598539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T06:24:04.186885Z","caller":"traceutil/trace.go:171","msg":"trace[1876897116] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:753; }","duration":"554.081962ms","start":"2026-02-24T06:24:03.632783Z","end":"2026-02-24T06:24:04.186865Z","steps":["trace[1876897116] 'read index received' (duration: 95.244108ms)","trace[1876897116] 'applied index is now lower than readState.Index' (duration: 458.836384ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:24:04.186932Z","caller":"traceutil/trace.go:171","msg":"trace[2019050307] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"555.298692ms","start":"2026-02-24T06:24:03.631615Z","end":"2026-02-24T06:24:04.186914Z","steps":["trace[2019050307] 'process raft request' (duration: 96.488338ms)","trace[2019050307] 'compare' (duration: 458.474816ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:24:04.187033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:03.631599Z","time spent":"555.381704ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:24:04.187051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.249666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T06:24:04.187114Z","caller":"traceutil/trace.go:171","msg":"trace[2189033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:720; }","duration":"554.351739ms","start":"2026-02-24T06:24:03.632746Z","end":"2026-02-24T06:24:04.187098Z","steps":["trace[2189033] 'agreement among raft nodes before linearized reading' (duration: 554.184945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:04.18715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:03.632731Z","time spent":"554.411781ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T06:24:04.687454Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609918069,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T06:24:04.756237Z","caller":"traceutil/trace.go:171","msg":"trace[651605906] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"1.123326703s","start":"2026-02-24T06:24:03.632884Z","end":"2026-02-24T06:24:04.75621Z","steps":["trace[651605906] 'process raft request' (duration: 1.112195895s)","trace[651605906] 'compare' (duration: 10.464982ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:24:04.756414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:03.632873Z","time spent":"1.123453486s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:24:04.756441Z","caller":"traceutil/trace.go:171","msg":"trace[116484222] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"1.123237541s","start":"2026-02-24T06:24:03.633182Z","end":"2026-02-24T06:24:04.756419Z","steps":["trace[116484222] 'process raft request' (duration: 1.12278731s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:04.756569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:03.63317Z","time spent":"1.123325203s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:24:04.756594Z","caller":"traceutil/trace.go:171","msg":"trace[1177310619] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"1.118460601s","start":"2026-02-24T06:24:03.638118Z","end":"2026-02-24T06:24:04.756578Z","steps":["trace[1177310619] 'process raft request' (duration: 1.118263826s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:04.756677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:03.638111Z","time spent":"1.118533093s","remote":"127.0.0.1:45806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2257,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T06:24:04.853875Z","caller":"traceutil/trace.go:171","msg":"trace[720184833] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:754; }","duration":"666.93794ms","start":"2026-02-24T06:24:04.186916Z","end":"2026-02-24T06:24:04.853854Z","steps":["trace[720184833] 'read index received' (duration: 558.176814ms)","trace[720184833] 'applied index is now lower than readState.Index' (duration: 108.760036ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:24:04.853933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217979456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:24:04.853921Z","caller":"traceutil/trace.go:171","msg":"trace[1161900862] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"662.539689ms","start":"2026-02-24T06:24:04.191357Z","end":"2026-02-24T06:24:04.853897Z","steps":["trace[1161900862] 'process raft request' (duration: 662.346804ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:04.853969Z","caller":"traceutil/trace.go:171","msg":"trace[1572282256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:724; }","duration":"1.218036497s","start":"2026-02-24T06:24:03.635923Z","end":"2026-02-24T06:24:04.85396Z","steps":["trace[1572282256] 'agreement among raft nodes before linearized reading' (duration: 1.217967355s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:04.853995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:03.635918Z","time spent":"1.218071618s","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T06:24:04.85404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:04.19134Z","time spent":"662.637003ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:24:04.854042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.221196866s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T06:24:04.854076Z","caller":"traceutil/trace.go:171","msg":"trace[943041267] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:724; }","duration":"1.221250187s","start":"2026-02-24T06:24:03.632817Z","end":"2026-02-24T06:24:04.854067Z","steps":["trace[943041267] 'agreement among raft nodes before linearized reading' (duration: 1.221113803s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:04.854108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:03.632812Z","time spent":"1.221287069s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T06:24:05.254144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.302643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T06:24:05.254245Z","caller":"traceutil/trace.go:171","msg":"trace[1437382468] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:758; }","duration":"391.856733ms","start":"2026-02-24T06:24:04.862362Z","end":"2026-02-24T06:24:05.254219Z","steps":["trace[1437382468] 'read index received' (duration: 261.418916ms)","trace[1437382468] 'applied index is now lower than readState.Index' (duration: 130.436937ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:24:05.254289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.929124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:24:05.254307Z","caller":"traceutil/trace.go:171","msg":"trace[934567328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:725; }","duration":"391.959976ms","start":"2026-02-24T06:24:04.862341Z","end":"2026-02-24T06:24:05.254301Z","steps":["trace[934567328] 'agreement among raft nodes before linearized reading' (duration: 391.923625ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:05.254298Z","caller":"traceutil/trace.go:171","msg":"trace[73698671] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"396.258653ms","start":"2026-02-24T06:24:04.858018Z","end":"2026-02-24T06:24:05.254277Z","steps":["trace[73698671] 'process raft request' (duration: 265.757385ms)","trace[73698671] 'compare' (duration: 130.18509ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:24:05.254346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:04.862335Z","time spent":"391.992356ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T06:24:05.25441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:04.857998Z","time spent":"396.345675ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:24:13.031042Z","caller":"traceutil/trace.go:171","msg":"trace[1370357685] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"136.352424ms","start":"2026-02-24T06:24:12.894657Z","end":"2026-02-24T06:24:13.03101Z","steps":["trace[1370357685] 'process raft request' (duration: 86.174782ms)","trace[1370357685] 'compare' (duration: 50.033279ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:24:14.746773Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609918194,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T06:24:14.831315Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.309819312s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T06:24:14.831714Z","caller":"traceutil/trace.go:171","msg":"trace[1411523721] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"585.670944ms","start":"2026-02-24T06:24:14.246024Z","end":"2026-02-24T06:24:14.831695Z","steps":["trace[1411523721] 'read index received' (duration: 585.52111ms)","trace[1411523721] 'applied index is now lower than readState.Index' (duration: 148.764µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:24:14.831992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.94031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-24T06:24:14.8321Z","caller":"traceutil/trace.go:171","msg":"trace[619973449] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:788; }","duration":"586.061623ms","start":"2026-02-24T06:24:14.246009Z","end":"2026-02-24T06:24:14.832071Z","steps":["trace[619973449] 'agreement among raft nodes before linearized reading' (duration: 585.773396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:14.83217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:14.245896Z","time spent":"586.258988ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T06:24:14.83177Z","caller":"traceutil/trace.go:171","msg":"trace[1722880116] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"1.545823683s","start":"2026-02-24T06:24:13.285918Z","end":"2026-02-24T06:24:14.831741Z","steps":["trace[1722880116] 'process raft request' (duration: 1.545641448s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:14.832513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:13.285888Z","time spent":"1.546496839s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:24:15.241403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.769626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T06:24:15.241676Z","caller":"traceutil/trace.go:171","msg":"trace[1096777691] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"405.789711ms","start":"2026-02-24T06:24:14.835858Z","end":"2026-02-24T06:24:15.241648Z","steps":["trace[1096777691] 'process raft request' (duration: 177.649315ms)","trace[1096777691] 'compare' (duration: 227.617863ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:24:15.241729Z","caller":"traceutil/trace.go:171","msg":"trace[1500447821] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"405.233717ms","start":"2026-02-24T06:24:14.836476Z","end":"2026-02-24T06:24:15.241709Z","steps":["trace[1500447821] 'process raft request' (duration: 405.112844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:15.241855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:14.836462Z","time spent":"405.34917ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:24:15.241848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:14.835836Z","time spent":"405.907523ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:24:15.242079Z","caller":"traceutil/trace.go:171","msg":"trace[2008177644] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:824; }","duration":"207.196453ms","start":"2026-02-24T06:24:15.034833Z","end":"2026-02-24T06:24:15.242029Z","steps":["trace[2008177644] 'read index received' (duration: 152.269562ms)","trace[2008177644] 'applied index is now lower than readState.Index' (duration: 54.924491ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:24:15.24219Z","caller":"traceutil/trace.go:171","msg":"trace[76996188] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"401.739818ms","start":"2026-02-24T06:24:14.840435Z","end":"2026-02-24T06:24:15.242175Z","steps":["trace[76996188] 'process raft request' (duration: 401.228346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:15.242262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:14.840406Z","time spent":"401.816591ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:24:15.243247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.411283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T06:24:15.243303Z","caller":"traceutil/trace.go:171","msg":"trace[683228397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:791; }","duration":"208.508736ms","start":"2026-02-24T06:24:15.034778Z","end":"2026-02-24T06:24:15.243286Z","steps":["trace[683228397] 'agreement among raft nodes before linearized reading' (duration: 207.409558ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:16.982892Z","caller":"traceutil/trace.go:171","msg":"trace[749288790] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"141.493003ms","start":"2026-02-24T06:24:16.841372Z","end":"2026-02-24T06:24:16.982865Z","steps":["trace[749288790] 'process raft request' (duration: 141.320989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:18.26366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.282613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-24T06:24:18.263748Z","caller":"traceutil/trace.go:171","msg":"trace[1683073758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:793; }","duration":"958.424087ms","start":"2026-02-24T06:24:17.305304Z","end":"2026-02-24T06:24:18.263728Z","steps":["trace[1683073758] 'range keys from in-memory index tree' (duration: 958.106889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:18.263809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:17.305286Z","time spent":"958.513398ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T06:24:18.263888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019207264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T06:24:18.263973Z","caller":"traceutil/trace.go:171","msg":"trace[1640757162] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; response_count:1; response_revision:793; }","duration":"1.019334578s","start":"2026-02-24T06:24:17.244617Z","end":"2026-02-24T06:24:18.263951Z","steps":["trace[1640757162] 'range keys from in-memory index tree' (duration: 1.019066371s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:18.264021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:17.244606Z","time spent":"1.0194049s","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.36\" "} {"level":"warn","ts":"2026-02-24T06:24:18.264003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017561844s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T06:24:18.264085Z","caller":"traceutil/trace.go:171","msg":"trace[1437891071] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:793; }","duration":"1.017669646s","start":"2026-02-24T06:24:17.246397Z","end":"2026-02-24T06:24:18.264067Z","steps":["trace[1437891071] 'range keys from in-memory index tree' (duration: 1.01745007s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:18.264099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018374874s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-24T06:24:18.264122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:17.246389Z","time spent":"1.017724787s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T06:24:18.264133Z","caller":"traceutil/trace.go:171","msg":"trace[417874623] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:793; }","duration":"1.018423115s","start":"2026-02-24T06:24:17.2457Z","end":"2026-02-24T06:24:18.264123Z","steps":["trace[417874623] 'range keys from in-memory index tree' (duration: 1.018306212s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:18.264157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:17.245694Z","time spent":"1.018456766s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T06:24:18.264171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015943423s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T06:24:18.264206Z","caller":"traceutil/trace.go:171","msg":"trace[1758598105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:793; }","duration":"1.015978344s","start":"2026-02-24T06:24:17.248218Z","end":"2026-02-24T06:24:18.264196Z","steps":["trace[1758598105] 'range keys from in-memory index tree' (duration: 1.015873451s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:18.264234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:17.248196Z","time spent":"1.016031815s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T06:24:18.264412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:17.849911Z","time spent":"414.483258ms","remote":"127.0.0.1:40048","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-24T06:24:18.264591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058227339s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:24:18.264653Z","caller":"traceutil/trace.go:171","msg":"trace[19514664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"1.05828838s","start":"2026-02-24T06:24:17.206324Z","end":"2026-02-24T06:24:18.264612Z","steps":["trace[19514664] 'range keys from in-memory index tree' (duration: 1.057214794s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:18.264674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:17.206306Z","time spent":"1.058362662s","remote":"127.0.0.1:46410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T06:24:18.773424Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609918215,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T06:24:19.27362Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609918215,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T06:24:19.460223Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.194928023s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-24T06:24:19.460653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:18.265182Z","time spent":"1.195465487s","remote":"127.0.0.1:46452","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-24T06:24:19.8707Z","caller":"traceutil/trace.go:171","msg":"trace[349131533] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:829; }","duration":"1.598085609s","start":"2026-02-24T06:24:18.272594Z","end":"2026-02-24T06:24:19.870679Z","steps":["trace[349131533] 'read index received' (duration: 1.187875928s)","trace[349131533] 'applied index is now lower than readState.Index' (duration: 410.206741ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:24:19.871052Z","caller":"traceutil/trace.go:171","msg":"trace[336672648] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"1.602688403s","start":"2026-02-24T06:24:18.268349Z","end":"2026-02-24T06:24:19.871038Z","steps":["trace[336672648] 'process raft request' (duration: 1.602121178s)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:19.871075Z","caller":"traceutil/trace.go:171","msg":"trace[2115606079] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"1.600780436s","start":"2026-02-24T06:24:18.270252Z","end":"2026-02-24T06:24:19.871033Z","steps":["trace[2115606079] 'process raft request' (duration: 1.600341124s)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:19.871083Z","caller":"traceutil/trace.go:171","msg":"trace[329873548] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"1.602812527s","start":"2026-02-24T06:24:18.268222Z","end":"2026-02-24T06:24:19.871035Z","steps":["trace[329873548] 'process raft request' (duration: 1.602072258s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:19.871162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:18.268342Z","time spent":"1.602750224s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:24:19.871213Z","caller":"traceutil/trace.go:171","msg":"trace[2046209030] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"1.602766345s","start":"2026-02-24T06:24:18.268437Z","end":"2026-02-24T06:24:19.871204Z","steps":["trace[2046209030] 'process raft request' (duration: 1.602102968s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:19.871269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:18.268432Z","time spent":"1.602810236s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:24:19.871272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:18.268208Z","time spent":"1.60296125s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:24:19.871368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.598776736s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:24:19.871408Z","caller":"traceutil/trace.go:171","msg":"trace[1456350610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"1.598847538s","start":"2026-02-24T06:24:18.27255Z","end":"2026-02-24T06:24:19.871397Z","steps":["trace[1456350610] 'agreement among raft nodes before linearized reading' (duration: 1.598790136s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:19.871431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:18.272533Z","time spent":"1.598891959s","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T06:24:19.871491Z","caller":"traceutil/trace.go:171","msg":"trace[1501968380] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"1.336752035s","start":"2026-02-24T06:24:18.534726Z","end":"2026-02-24T06:24:19.871478Z","steps":["trace[1501968380] 'process raft request' (duration: 1.335913303s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:19.871655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:18.534698Z","time spent":"1.336916598s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:24:19.871164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:18.270245Z","time spent":"1.600860678s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:24:19.887498Z","caller":"traceutil/trace.go:171","msg":"trace[1333919456] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"425.157384ms","start":"2026-02-24T06:24:19.46232Z","end":"2026-02-24T06:24:19.887478Z","steps":["trace[1333919456] 'process raft request' (duration: 425.00181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:19.887601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:19.462287Z","time spent":"425.264447ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:24:19.887629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.431177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T06:24:19.887698Z","caller":"traceutil/trace.go:171","msg":"trace[1121049191] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"901.538739ms","start":"2026-02-24T06:24:18.986146Z","end":"2026-02-24T06:24:19.887684Z","steps":["trace[1121049191] 'agreement among raft nodes before linearized reading' (duration: 901.314042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:19.887732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:18.986125Z","time spent":"901.599551ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T06:24:19.887583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.254664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:24:19.888131Z","caller":"traceutil/trace.go:171","msg":"trace[273176380] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:799; }","duration":"382.707495ms","start":"2026-02-24T06:24:19.505274Z","end":"2026-02-24T06:24:19.887982Z","steps":["trace[273176380] 'agreement among raft nodes before linearized reading' (duration: 382.170651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:19.888314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:19.505246Z","time spent":"383.044714ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-24T06:24:21.362253Z","caller":"traceutil/trace.go:171","msg":"trace[1448697533] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:838; }","duration":"158.266822ms","start":"2026-02-24T06:24:21.203954Z","end":"2026-02-24T06:24:21.362221Z","steps":["trace[1448697533] 'read index received' (duration: 157.966414ms)","trace[1448697533] 'applied index is now lower than readState.Index' (duration: 298.888µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:24:21.362283Z","caller":"traceutil/trace.go:171","msg":"trace[380416263] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"169.601024ms","start":"2026-02-24T06:24:21.192653Z","end":"2026-02-24T06:24:21.362254Z","steps":["trace[380416263] 'process raft request' (duration: 169.370609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:21.362457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.477417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:24:21.362549Z","caller":"traceutil/trace.go:171","msg":"trace[690678940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"158.60429ms","start":"2026-02-24T06:24:21.203926Z","end":"2026-02-24T06:24:21.36253Z","steps":["trace[690678940] 'agreement among raft nodes before linearized reading' (duration: 158.412836ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:22.294941Z","caller":"traceutil/trace.go:171","msg":"trace[658073045] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:840; }","duration":"368.48322ms","start":"2026-02-24T06:24:21.926433Z","end":"2026-02-24T06:24:22.294916Z","steps":["trace[658073045] 'read index received' (duration: 368.159842ms)","trace[658073045] 'applied index is now lower than readState.Index' (duration: 322.518µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:24:22.295061Z","caller":"traceutil/trace.go:171","msg":"trace[1792963328] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"414.603381ms","start":"2026-02-24T06:24:21.880445Z","end":"2026-02-24T06:24:22.295048Z","steps":["trace[1792963328] 'process raft request' (duration: 414.244772ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:22.295219Z","caller":"traceutil/trace.go:171","msg":"trace[702551735] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"414.655323ms","start":"2026-02-24T06:24:21.880552Z","end":"2026-02-24T06:24:22.295207Z","steps":["trace[702551735] 'process raft request' (duration: 414.327425ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:22.295206Z","caller":"traceutil/trace.go:171","msg":"trace[1268246796] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"414.645053ms","start":"2026-02-24T06:24:21.880543Z","end":"2026-02-24T06:24:22.295188Z","steps":["trace[1268246796] 'process raft request' (duration: 414.296474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:22.295284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:21.880544Z","time spent":"414.705944ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:24:22.295299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:21.880531Z","time spent":"414.724534ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:24:22.29539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.920381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T06:24:22.295471Z","caller":"traceutil/trace.go:171","msg":"trace[373707177] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:806; }","duration":"369.081465ms","start":"2026-02-24T06:24:21.92637Z","end":"2026-02-24T06:24:22.295452Z","steps":["trace[373707177] 'agreement among raft nodes before linearized reading' (duration: 368.813449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:22.295515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:21.926342Z","time spent":"369.164038ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T06:24:22.295175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:21.88041Z","time spent":"414.677863ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:24:22.65035Z","caller":"traceutil/trace.go:171","msg":"trace[517111473] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"351.066985ms","start":"2026-02-24T06:24:22.299253Z","end":"2026-02-24T06:24:22.65032Z","steps":["trace[517111473] 'process raft request' (duration: 316.493522ms)","trace[517111473] 'compare' (duration: 34.44723ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:24:22.650524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:22.299235Z","time spent":"351.203579ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:24:23.915801Z","caller":"traceutil/trace.go:171","msg":"trace[1831480023] transaction","detail":"{read_only:false; number_of_response:1; response_revision:808; }","duration":"101.291398ms","start":"2026-02-24T06:24:23.814487Z","end":"2026-02-24T06:24:23.915778Z","steps":["trace[1831480023] 'process raft request' (duration: 101.036152ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:24.40421Z","caller":"traceutil/trace.go:171","msg":"trace[1741660769] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"102.254803ms","start":"2026-02-24T06:24:24.301934Z","end":"2026-02-24T06:24:24.404189Z","steps":["trace[1741660769] 'process raft request' (duration: 98.034937ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:24.406025Z","caller":"traceutil/trace.go:171","msg":"trace[1205577899] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"102.335234ms","start":"2026-02-24T06:24:24.303673Z","end":"2026-02-24T06:24:24.406009Z","steps":["trace[1205577899] 'process raft request' (duration: 101.970726ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:24.406169Z","caller":"traceutil/trace.go:171","msg":"trace[1957478066] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"101.924545ms","start":"2026-02-24T06:24:24.304221Z","end":"2026-02-24T06:24:24.406146Z","steps":["trace[1957478066] 'process raft request' (duration: 101.569255ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:34.9009Z","caller":"traceutil/trace.go:171","msg":"trace[723672471] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:936; }","duration":"151.759079ms","start":"2026-02-24T06:24:34.749116Z","end":"2026-02-24T06:24:34.900875Z","steps":["trace[723672471] 'read index received' (duration: 144.866406ms)","trace[723672471] 'applied index is now lower than readState.Index' (duration: 6.891313ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:24:34.901107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.969044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T06:24:34.901165Z","caller":"traceutil/trace.go:171","msg":"trace[1828652939] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:896; }","duration":"152.076867ms","start":"2026-02-24T06:24:34.749076Z","end":"2026-02-24T06:24:34.901153Z","steps":["trace[1828652939] 'agreement among raft nodes before linearized reading' (duration: 151.910192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:46.705739Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609918527,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T06:24:47.055892Z","caller":"traceutil/trace.go:171","msg":"trace[1897412490] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"850.646266ms","start":"2026-02-24T06:24:46.205223Z","end":"2026-02-24T06:24:47.055869Z","steps":["trace[1897412490] 'read index received' (duration: 850.504032ms)","trace[1897412490] 'applied index is now lower than readState.Index' (duration: 141.224µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:24:47.056041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.805649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:24:47.056094Z","caller":"traceutil/trace.go:171","msg":"trace[1658347598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:926; }","duration":"850.909382ms","start":"2026-02-24T06:24:46.205174Z","end":"2026-02-24T06:24:47.056084Z","steps":["trace[1658347598] 'agreement among raft nodes before linearized reading' (duration: 850.82325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:47.056127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:46.205155Z","time spent":"850.964404ms","remote":"127.0.0.1:46410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T06:24:47.056205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.136596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T06:24:47.056263Z","caller":"traceutil/trace.go:171","msg":"trace[703578018] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:926; }","duration":"772.240828ms","start":"2026-02-24T06:24:46.284012Z","end":"2026-02-24T06:24:47.056253Z","steps":["trace[703578018] 'agreement among raft nodes before linearized reading' (duration: 772.134165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:47.056292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:46.283998Z","time spent":"772.2866ms","remote":"127.0.0.1:46826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-02-24T06:24:47.056015Z","caller":"traceutil/trace.go:171","msg":"trace[240255668] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"995.468551ms","start":"2026-02-24T06:24:46.060509Z","end":"2026-02-24T06:24:47.055978Z","steps":["trace[240255668] 'process raft request' (duration: 995.233466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:47.056591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.456696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-24T06:24:47.056596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.475327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T06:24:47.056619Z","caller":"traceutil/trace.go:171","msg":"trace[1857255275] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:926; }","duration":"549.485007ms","start":"2026-02-24T06:24:46.507127Z","end":"2026-02-24T06:24:47.056612Z","steps":["trace[1857255275] 'agreement among raft nodes before linearized reading' (duration: 549.423375ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:47.05663Z","caller":"traceutil/trace.go:171","msg":"trace[64967140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:926; }","duration":"541.526788ms","start":"2026-02-24T06:24:46.515094Z","end":"2026-02-24T06:24:47.056621Z","steps":["trace[64967140] 'agreement among raft nodes before linearized reading' (duration: 541.416115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:47.056639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:46.507072Z","time spent":"549.562268ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T06:24:47.056633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:46.06049Z","time spent":"995.986934ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:24:47.056658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:46.515087Z","time spent":"541.564989ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T06:24:47.056782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.361744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T06:24:47.056805Z","caller":"traceutil/trace.go:171","msg":"trace[1698208088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:926; }","duration":"541.395414ms","start":"2026-02-24T06:24:46.515403Z","end":"2026-02-24T06:24:47.056798Z","steps":["trace[1698208088] 'agreement among raft nodes before linearized reading' (duration: 541.318663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:47.056828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:46.515397Z","time spent":"541.425296ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-24T06:24:47.249036Z","caller":"traceutil/trace.go:171","msg":"trace[353861643] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"188.679521ms","start":"2026-02-24T06:24:47.060334Z","end":"2026-02-24T06:24:47.249014Z","steps":["trace[353861643] 'process raft request' (duration: 188.543827ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:47.806307Z","caller":"traceutil/trace.go:171","msg":"trace[1893319150] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"745.218963ms","start":"2026-02-24T06:24:47.061066Z","end":"2026-02-24T06:24:47.806285Z","steps":["trace[1893319150] 'process raft request' (duration: 741.588042ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:47.806465Z","caller":"traceutil/trace.go:171","msg":"trace[118251523] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"745.359107ms","start":"2026-02-24T06:24:47.061091Z","end":"2026-02-24T06:24:47.80645Z","steps":["trace[118251523] 'process raft request' (duration: 745.155982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:47.806564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:47.061075Z","time spent":"745.424948ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:24:47.806645Z","caller":"traceutil/trace.go:171","msg":"trace[2096815453] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"745.371797ms","start":"2026-02-24T06:24:47.061258Z","end":"2026-02-24T06:24:47.80663Z","steps":["trace[2096815453] 'process raft request' (duration: 745.145582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:47.806719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:47.061246Z","time spent":"745.432019ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:24:47.806758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.769742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:24:47.806812Z","caller":"traceutil/trace.go:171","msg":"trace[934689218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:930; }","duration":"601.893146ms","start":"2026-02-24T06:24:47.204902Z","end":"2026-02-24T06:24:47.806795Z","steps":["trace[934689218] 'agreement among raft nodes before linearized reading' (duration: 601.772203ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:47.806612Z","caller":"traceutil/trace.go:171","msg":"trace[1370190434] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:969; }","duration":"601.622579ms","start":"2026-02-24T06:24:47.204945Z","end":"2026-02-24T06:24:47.806568Z","steps":["trace[1370190434] 'read index received' (duration: 43.992049ms)","trace[1370190434] 'applied index is now lower than readState.Index' (duration: 557.62809ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:24:47.80688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:47.204882Z","time spent":"601.964817ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T06:24:47.807039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.230645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T06:24:47.807078Z","caller":"traceutil/trace.go:171","msg":"trace[2130494570] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; response_count:1; response_revision:930; }","duration":"560.304747ms","start":"2026-02-24T06:24:47.246761Z","end":"2026-02-24T06:24:47.807066Z","steps":["trace[2130494570] 'agreement among raft nodes before linearized reading' (duration: 560.186564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:47.807111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:47.246745Z","time spent":"560.358018ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.36\" "} {"level":"warn","ts":"2026-02-24T06:24:47.807121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.080036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T06:24:47.807172Z","caller":"traceutil/trace.go:171","msg":"trace[1145443374] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:930; }","duration":"162.175628ms","start":"2026-02-24T06:24:47.644981Z","end":"2026-02-24T06:24:47.807157Z","steps":["trace[1145443374] 'agreement among raft nodes before linearized reading' (duration: 162.088456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:24:47.807353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:24:47.061048Z","time spent":"745.328406ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:24:51.375303Z","caller":"traceutil/trace.go:171","msg":"trace[1561273537] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"111.138697ms","start":"2026-02-24T06:24:51.264132Z","end":"2026-02-24T06:24:51.375271Z","steps":["trace[1561273537] 'process raft request' (duration: 97.135906ms)","trace[1561273537] 'compare' (duration: 13.748505ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:24:56.103835Z","caller":"traceutil/trace.go:171","msg":"trace[703817797] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:995; }","duration":"181.177783ms","start":"2026-02-24T06:24:55.922625Z","end":"2026-02-24T06:24:56.103803Z","steps":["trace[703817797] 'read index received' (duration: 54.470635ms)","trace[703817797] 'applied index is now lower than readState.Index' (duration: 126.706158ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:24:56.103863Z","caller":"traceutil/trace.go:171","msg":"trace[1828577888] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"201.218996ms","start":"2026-02-24T06:24:55.902609Z","end":"2026-02-24T06:24:56.103828Z","steps":["trace[1828577888] 'process raft request' (duration: 120.332698ms)","trace[1828577888] 'compare' (duration: 80.654551ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:24:56.103979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.320717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:24:56.104051Z","caller":"traceutil/trace.go:171","msg":"trace[668398426] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:953; }","duration":"181.46043ms","start":"2026-02-24T06:24:55.92258Z","end":"2026-02-24T06:24:56.10404Z","steps":["trace[668398426] 'agreement among raft nodes before linearized reading' (duration: 181.330857ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:24:56.10464Z","caller":"traceutil/trace.go:171","msg":"trace[1867629643] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"200.742494ms","start":"2026-02-24T06:24:55.903854Z","end":"2026-02-24T06:24:56.104596Z","steps":["trace[1867629643] 'process raft request' (duration: 199.866152ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:25:00.16872Z","caller":"traceutil/trace.go:171","msg":"trace[301290112] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"162.640118ms","start":"2026-02-24T06:25:00.006048Z","end":"2026-02-24T06:25:00.168689Z","steps":["trace[301290112] 'process raft request' (duration: 162.33734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:00.667699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.889958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T06:25:00.667951Z","caller":"traceutil/trace.go:171","msg":"trace[186680330] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1008; }","duration":"460.351493ms","start":"2026-02-24T06:25:00.207576Z","end":"2026-02-24T06:25:00.667927Z","steps":["trace[186680330] 'read index received' (duration: 58.010784ms)","trace[186680330] 'applied index is now lower than readState.Index' (duration: 402.339199ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:25:00.66807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.496138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:25:00.668116Z","caller":"traceutil/trace.go:171","msg":"trace[803351139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"460.57606ms","start":"2026-02-24T06:25:00.207525Z","end":"2026-02-24T06:25:00.668101Z","steps":["trace[803351139] 'agreement among raft nodes before linearized reading' (duration: 460.481087ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:25:00.668107Z","caller":"traceutil/trace.go:171","msg":"trace[1502416337] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"493.685429ms","start":"2026-02-24T06:25:00.174379Z","end":"2026-02-24T06:25:00.668065Z","steps":["trace[1502416337] 'process raft request' (duration: 493.474544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:00.668163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:00.207501Z","time spent":"460.651601ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T06:25:00.668219Z","caller":"traceutil/trace.go:171","msg":"trace[976341684] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"494.382927ms","start":"2026-02-24T06:25:00.173803Z","end":"2026-02-24T06:25:00.668186Z","steps":["trace[976341684] 'process raft request' (duration: 91.887324ms)","trace[976341684] 'compare' (duration: 401.695873ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:25:00.668327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:00.174364Z","time spent":"493.850412ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:25:00.668391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:00.173746Z","time spent":"494.569912ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:25:07.193818Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609918717,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T06:25:07.319022Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.118444745s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T06:25:07.319416Z","caller":"traceutil/trace.go:171","msg":"trace[1030294384] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"626.044668ms","start":"2026-02-24T06:25:06.693335Z","end":"2026-02-24T06:25:07.31938Z","steps":["trace[1030294384] 'read index received' (duration: 625.815362ms)","trace[1030294384] 'applied index is now lower than readState.Index' (duration: 228.246µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:25:07.319506Z","caller":"traceutil/trace.go:171","msg":"trace[890250177] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"1.11903243s","start":"2026-02-24T06:25:06.200462Z","end":"2026-02-24T06:25:07.319495Z","steps":["trace[890250177] 'process raft request' (duration: 1.118757783s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:07.319621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:06.200442Z","time spent":"1.119098481s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:25:07.319931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.224352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T06:25:07.31999Z","caller":"traceutil/trace.go:171","msg":"trace[480439298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1006; }","duration":"626.292023ms","start":"2026-02-24T06:25:06.693671Z","end":"2026-02-24T06:25:07.319963Z","steps":["trace[480439298] 'agreement among raft nodes before linearized reading' (duration: 626.15003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:07.320023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:06.69366Z","time spent":"626.355516ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T06:25:07.320147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.817787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T06:25:07.320173Z","caller":"traceutil/trace.go:171","msg":"trace[830876003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1006; }","duration":"626.893738ms","start":"2026-02-24T06:25:06.693271Z","end":"2026-02-24T06:25:07.320165Z","steps":["trace[830876003] 'agreement among raft nodes before linearized reading' (duration: 626.820346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:07.320199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:06.693252Z","time spent":"626.94106ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T06:25:07.320366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.516319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:25:07.320439Z","caller":"traceutil/trace.go:171","msg":"trace[1122739401] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1006; }","duration":"137.599311ms","start":"2026-02-24T06:25:07.18282Z","end":"2026-02-24T06:25:07.320419Z","steps":["trace[1122739401] 'agreement among raft nodes before linearized reading' (duration: 137.478538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:07.320613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.874286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:25:07.320636Z","caller":"traceutil/trace.go:171","msg":"trace[965443315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1006; }","duration":"113.924577ms","start":"2026-02-24T06:25:07.206706Z","end":"2026-02-24T06:25:07.32063Z","steps":["trace[965443315] 'agreement among raft nodes before linearized reading' (duration: 113.882566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:07.671321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.272716ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-24T06:25:07.671734Z","caller":"traceutil/trace.go:171","msg":"trace[581961945] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"346.793876ms","start":"2026-02-24T06:25:07.324916Z","end":"2026-02-24T06:25:07.67171Z","steps":["trace[581961945] 'process raft request' (duration: 346.743764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:07.671745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:07.321037Z","time spent":"350.702783ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-24T06:25:07.67185Z","caller":"traceutil/trace.go:171","msg":"trace[999802408] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"346.964901ms","start":"2026-02-24T06:25:07.324863Z","end":"2026-02-24T06:25:07.671828Z","steps":["trace[999802408] 'process raft request' (duration: 346.742585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:07.671873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:07.324902Z","time spent":"346.893279ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:25:07.671861Z","caller":"traceutil/trace.go:171","msg":"trace[1481120647] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"347.633507ms","start":"2026-02-24T06:25:07.324191Z","end":"2026-02-24T06:25:07.671825Z","steps":["trace[1481120647] 'process raft request' (duration: 347.269638ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:25:07.672008Z","caller":"traceutil/trace.go:171","msg":"trace[884284439] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"350.636472ms","start":"2026-02-24T06:25:07.321352Z","end":"2026-02-24T06:25:07.671988Z","steps":["trace[884284439] 'read index received' (duration: 150.662278ms)","trace[884284439] 'applied index is now lower than readState.Index' (duration: 199.972474ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:25:07.672033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:07.324157Z","time spent":"347.77663ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:25:07.672043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:07.324857Z","time spent":"347.053433ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:25:07.672145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.785615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:25:07.672178Z","caller":"traceutil/trace.go:171","msg":"trace[2048040585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"350.844217ms","start":"2026-02-24T06:25:07.321322Z","end":"2026-02-24T06:25:07.672166Z","steps":["trace[2048040585] 'agreement among raft nodes before linearized reading' (duration: 350.788526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:07.672219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:07.321308Z","time spent":"350.900488ms","remote":"127.0.0.1:46410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T06:25:07.6726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.303895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T06:25:07.672691Z","caller":"traceutil/trace.go:171","msg":"trace[1706631522] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1009; }","duration":"165.457038ms","start":"2026-02-24T06:25:07.507212Z","end":"2026-02-24T06:25:07.672669Z","steps":["trace[1706631522] 'agreement among raft nodes before linearized reading' (duration: 165.170771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:07.947568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.935959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T06:25:07.94785Z","caller":"traceutil/trace.go:171","msg":"trace[332290317] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1059; }","duration":"237.332081ms","start":"2026-02-24T06:25:07.710501Z","end":"2026-02-24T06:25:07.947833Z","steps":["trace[332290317] 'read index received' (duration: 92.984242ms)","trace[332290317] 'applied index is now lower than readState.Index' (duration: 144.346279ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:25:07.947953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.456295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-24T06:25:07.947985Z","caller":"traceutil/trace.go:171","msg":"trace[960734269] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1011; }","duration":"237.517527ms","start":"2026-02-24T06:25:07.710459Z","end":"2026-02-24T06:25:07.947977Z","steps":["trace[960734269] 'agreement among raft nodes before linearized reading' (duration: 237.418473ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:25:07.948077Z","caller":"traceutil/trace.go:171","msg":"trace[404107943] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"270.442902ms","start":"2026-02-24T06:25:07.677608Z","end":"2026-02-24T06:25:07.948051Z","steps":["trace[404107943] 'process raft request' (duration: 125.939538ms)","trace[404107943] 'compare' (duration: 143.820026ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:25:09.896567Z","caller":"traceutil/trace.go:171","msg":"trace[166806015] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"253.24281ms","start":"2026-02-24T06:25:09.643299Z","end":"2026-02-24T06:25:09.896542Z","steps":["trace[166806015] 'read index received' (duration: 252.960783ms)","trace[166806015] 'applied index is now lower than readState.Index' (duration: 280.877µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:25:09.89666Z","caller":"traceutil/trace.go:171","msg":"trace[692841791] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"550.360722ms","start":"2026-02-24T06:25:09.346262Z","end":"2026-02-24T06:25:09.896623Z","steps":["trace[692841791] 'process raft request' (duration: 550.017503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:09.896883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:09.346245Z","time spent":"550.550086ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:25:09.897049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.657179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-24T06:25:09.897128Z","caller":"traceutil/trace.go:171","msg":"trace[1914748937] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1012; }","duration":"253.853544ms","start":"2026-02-24T06:25:09.643248Z","end":"2026-02-24T06:25:09.897101Z","steps":["trace[1914748937] 'agreement among raft nodes before linearized reading' (duration: 253.381783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:09.897137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.76485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-24T06:25:09.897118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.691624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T06:25:09.89719Z","caller":"traceutil/trace.go:171","msg":"trace[335059745] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1012; }","duration":"219.862462ms","start":"2026-02-24T06:25:09.677314Z","end":"2026-02-24T06:25:09.897176Z","steps":["trace[335059745] 'agreement among raft nodes before linearized reading' (duration: 219.520234ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:25:09.897239Z","caller":"traceutil/trace.go:171","msg":"trace[625175634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1012; }","duration":"218.848927ms","start":"2026-02-24T06:25:09.678364Z","end":"2026-02-24T06:25:09.897213Z","steps":["trace[625175634] 'agreement among raft nodes before linearized reading' (duration: 218.515219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:09.897114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.695048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T06:25:09.897519Z","caller":"traceutil/trace.go:171","msg":"trace[701340351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1012; }","duration":"220.157489ms","start":"2026-02-24T06:25:09.677345Z","end":"2026-02-24T06:25:09.897502Z","steps":["trace[701340351] 'agreement among raft nodes before linearized reading' (duration: 219.548284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:10.060947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.927691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T06:25:10.061232Z","caller":"traceutil/trace.go:171","msg":"trace[636594263] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1061; }","duration":"109.782492ms","start":"2026-02-24T06:25:09.951364Z","end":"2026-02-24T06:25:10.061146Z","steps":["trace[636594263] 'read index received' (duration: 570.774µs)","trace[636594263] 'applied index is now lower than readState.Index' (duration: 109.210058ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:25:10.061214Z","caller":"traceutil/trace.go:171","msg":"trace[435615012] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"158.844513ms","start":"2026-02-24T06:25:09.902334Z","end":"2026-02-24T06:25:10.061178Z","steps":["trace[435615012] 'process raft request' (duration: 49.599214ms)","trace[435615012] 'compare' (duration: 108.780188ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:25:10.061424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.07259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T06:25:10.061404Z","caller":"traceutil/trace.go:171","msg":"trace[921700768] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"158.565816ms","start":"2026-02-24T06:25:09.902728Z","end":"2026-02-24T06:25:10.061294Z","steps":["trace[921700768] 'process raft request' (duration: 158.365481ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:25:10.061453Z","caller":"traceutil/trace.go:171","msg":"trace[10562504] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1016; }","duration":"110.148352ms","start":"2026-02-24T06:25:09.951297Z","end":"2026-02-24T06:25:10.061446Z","steps":["trace[10562504] 'agreement among raft nodes before linearized reading' (duration: 109.991588ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:25:10.061529Z","caller":"traceutil/trace.go:171","msg":"trace[1778521770] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"158.7015ms","start":"2026-02-24T06:25:09.902816Z","end":"2026-02-24T06:25:10.061517Z","steps":["trace[1778521770] 'process raft request' (duration: 158.30348ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:25:10.061632Z","caller":"traceutil/trace.go:171","msg":"trace[1782566165] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"159.001397ms","start":"2026-02-24T06:25:09.902619Z","end":"2026-02-24T06:25:10.06162Z","steps":["trace[1782566165] 'process raft request' (duration: 158.433003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:10.559246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.916888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T06:25:10.55936Z","caller":"traceutil/trace.go:171","msg":"trace[1592310334] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1065; }","duration":"350.967601ms","start":"2026-02-24T06:25:10.208374Z","end":"2026-02-24T06:25:10.559342Z","steps":["trace[1592310334] 'read index received' (duration: 43.981µs)","trace[1592310334] 'applied index is now lower than readState.Index' (duration: 350.92179ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:25:10.559443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.069154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:25:10.559469Z","caller":"traceutil/trace.go:171","msg":"trace[666648654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"351.141906ms","start":"2026-02-24T06:25:10.208317Z","end":"2026-02-24T06:25:10.559459Z","steps":["trace[666648654] 'agreement among raft nodes before linearized reading' (duration: 351.072254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:10.559503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:10.208296Z","time spent":"351.199807ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T06:25:10.559575Z","caller":"traceutil/trace.go:171","msg":"trace[187635650] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"493.571397ms","start":"2026-02-24T06:25:10.065968Z","end":"2026-02-24T06:25:10.55954Z","steps":["trace[187635650] 'process raft request' (duration: 95.262699ms)","trace[187635650] 'compare' (duration: 397.784465ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:25:10.559738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:10.065945Z","time spent":"493.70997ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:25:11.162037Z","caller":"traceutil/trace.go:171","msg":"trace[1413957250] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"164.864284ms","start":"2026-02-24T06:25:10.997155Z","end":"2026-02-24T06:25:11.162019Z","steps":["trace[1413957250] 'process raft request' (duration: 164.746591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:11.424782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.858838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T06:25:11.424929Z","caller":"traceutil/trace.go:171","msg":"trace[1086880872] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"273.788856ms","start":"2026-02-24T06:25:11.151116Z","end":"2026-02-24T06:25:11.424905Z","steps":["trace[1086880872] 'process raft request' (duration: 70.692503ms)","trace[1086880872] 'compare' (duration: 202.719834ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:25:11.487209Z","caller":"traceutil/trace.go:171","msg":"trace[923304851] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"206.873467ms","start":"2026-02-24T06:25:11.280302Z","end":"2026-02-24T06:25:11.487175Z","steps":["trace[923304851] 'process raft request' (duration: 206.603401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:12.566225Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609918752,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T06:25:12.752095Z","caller":"traceutil/trace.go:171","msg":"trace[1836062565] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"845.83961ms","start":"2026-02-24T06:25:11.906226Z","end":"2026-02-24T06:25:12.752065Z","steps":["trace[1836062565] 'process raft request' (duration: 845.674085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:12.752306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:11.906204Z","time spent":"846.002943ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:25:12.752503Z","caller":"traceutil/trace.go:171","msg":"trace[1866035104] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1070; }","duration":"686.795932ms","start":"2026-02-24T06:25:12.065658Z","end":"2026-02-24T06:25:12.752454Z","steps":["trace[1866035104] 'read index received' (duration: 686.766731ms)","trace[1866035104] 'applied index is now lower than readState.Index' (duration: 19.301µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:25:12.752675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.019207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T06:25:12.752728Z","caller":"traceutil/trace.go:171","msg":"trace[382541834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1021; }","duration":"687.1111ms","start":"2026-02-24T06:25:12.065603Z","end":"2026-02-24T06:25:12.752714Z","steps":["trace[382541834] 'agreement among raft nodes before linearized reading' (duration: 686.947326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:12.752763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:12.065583Z","time spent":"687.173321ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T06:25:13.252908Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582802609918755,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T06:25:13.820566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.516382ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-24T06:25:13.820831Z","caller":"traceutil/trace.go:171","msg":"trace[790085133] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1070; }","duration":"1.068201745s","start":"2026-02-24T06:25:12.752611Z","end":"2026-02-24T06:25:13.820813Z","steps":["trace[790085133] 'read index received' (duration: 612.440956ms)","trace[790085133] 'applied index is now lower than readState.Index' (duration: 455.759509ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:25:13.820951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.740115733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T06:25:13.820926Z","caller":"traceutil/trace.go:171","msg":"trace[1354796265] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"1.063602271s","start":"2026-02-24T06:25:12.757289Z","end":"2026-02-24T06:25:13.820892Z","steps":["trace[1354796265] 'process raft request' (duration: 1.063388436s)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:25:13.820987Z","caller":"traceutil/trace.go:171","msg":"trace[564042012] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1022; }","duration":"1.740185505s","start":"2026-02-24T06:25:12.08079Z","end":"2026-02-24T06:25:13.820976Z","steps":["trace[564042012] 'agreement among raft nodes before linearized reading' (duration: 1.740110833s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:13.820965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.614869832s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T06:25:13.821025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:12.080778Z","time spent":"1.740237136s","remote":"127.0.0.1:45834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-02-24T06:25:13.821041Z","caller":"traceutil/trace.go:171","msg":"trace[1658615813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"1.614981335s","start":"2026-02-24T06:25:12.206038Z","end":"2026-02-24T06:25:13.82102Z","steps":["trace[1658615813] 'agreement among raft nodes before linearized reading' (duration: 1.614804731s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:13.821089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:12.206025Z","time spent":"1.615055908s","remote":"127.0.0.1:46410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T06:25:13.821134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:12.757255Z","time spent":"1.063749884s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:25:13.821179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256969719s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T06:25:13.821233Z","caller":"traceutil/trace.go:171","msg":"trace[273071330] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1022; }","duration":"1.257074931s","start":"2026-02-24T06:25:12.564144Z","end":"2026-02-24T06:25:13.821219Z","steps":["trace[273071330] 'agreement among raft nodes before linearized reading' (duration: 1.256944398s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:13.821262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:12.564117Z","time spent":"1.257139403s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T06:25:13.821305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.754607136s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T06:25:13.821336Z","caller":"traceutil/trace.go:171","msg":"trace[1601033095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1022; }","duration":"1.754682068s","start":"2026-02-24T06:25:12.066645Z","end":"2026-02-24T06:25:13.821327Z","steps":["trace[1601033095] 'agreement among raft nodes before linearized reading' (duration: 1.754562615s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:13.821363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:12.066625Z","time spent":"1.75473184s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T06:25:13.821556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.755850888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T06:25:13.821596Z","caller":"traceutil/trace.go:171","msg":"trace[193893385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1022; }","duration":"1.75594533s","start":"2026-02-24T06:25:12.065641Z","end":"2026-02-24T06:25:13.821586Z","steps":["trace[193893385] 'agreement among raft nodes before linearized reading' (duration: 1.755809917s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:13.821634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:12.065611Z","time spent":"1.756013982s","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T06:25:13.821642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.39062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T06:25:13.821672Z","caller":"traceutil/trace.go:171","msg":"trace[1798663643] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1022; }","duration":"865.624536ms","start":"2026-02-24T06:25:12.956036Z","end":"2026-02-24T06:25:13.821661Z","steps":["trace[1798663643] 'agreement among raft nodes before linearized reading' (duration: 865.402771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:25:13.821702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:25:12.956013Z","time spent":"865.683147ms","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-02-24T06:26:30.524665Z","caller":"traceutil/trace.go:171","msg":"trace[1586611387] linearizableReadLoop","detail":"{readStateIndex:1428; appliedIndex:1426; }","duration":"160.32029ms","start":"2026-02-24T06:26:30.364318Z","end":"2026-02-24T06:26:30.524638Z","steps":["trace[1586611387] 'read index received' (duration: 21.58811ms)","trace[1586611387] 'applied index is now lower than readState.Index' (duration: 138.73108ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:26:30.524809Z","caller":"traceutil/trace.go:171","msg":"trace[377870249] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"163.576532ms","start":"2026-02-24T06:26:30.3612Z","end":"2026-02-24T06:26:30.524776Z","steps":["trace[377870249] 'process raft request' (duration: 103.927979ms)","trace[377870249] 'compare' (duration: 59.362696ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:26:30.52503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.680639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T06:26:30.525079Z","caller":"traceutil/trace.go:171","msg":"trace[343356000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1363; }","duration":"160.776242ms","start":"2026-02-24T06:26:30.364289Z","end":"2026-02-24T06:26:30.525065Z","steps":["trace[343356000] 'agreement among raft nodes before linearized reading' (duration: 160.509475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:26:30.525178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.553615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T06:26:30.525307Z","caller":"traceutil/trace.go:171","msg":"trace[196913790] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1363; }","duration":"114.74876ms","start":"2026-02-24T06:26:30.410533Z","end":"2026-02-24T06:26:30.525282Z","steps":["trace[196913790] 'agreement among raft nodes before linearized reading' (duration: 114.508174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:26:30.525429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.000558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T06:26:30.525607Z","caller":"traceutil/trace.go:171","msg":"trace[705221199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1363; }","duration":"161.237693ms","start":"2026-02-24T06:26:30.364318Z","end":"2026-02-24T06:26:30.525556Z","steps":["trace[705221199] 'agreement among raft nodes before linearized reading' (duration: 160.656119ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:26:30.720202Z","caller":"traceutil/trace.go:171","msg":"trace[1906796069] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"191.091569ms","start":"2026-02-24T06:26:30.529081Z","end":"2026-02-24T06:26:30.720173Z","steps":["trace[1906796069] 'process raft request' (duration: 172.604538ms)","trace[1906796069] 'compare' (duration: 18.358428ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:28:17.080961Z","caller":"traceutil/trace.go:171","msg":"trace[1760201856] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"119.773234ms","start":"2026-02-24T06:28:16.961131Z","end":"2026-02-24T06:28:17.080904Z","steps":["trace[1760201856] 'process raft request' (duration: 119.463926ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:28:18.503593Z","caller":"traceutil/trace.go:171","msg":"trace[328797424] linearizableReadLoop","detail":"{readStateIndex:1916; appliedIndex:1915; }","duration":"296.719859ms","start":"2026-02-24T06:28:18.206842Z","end":"2026-02-24T06:28:18.503561Z","steps":["trace[328797424] 'read index received' (duration: 296.383329ms)","trace[328797424] 'applied index is now lower than readState.Index' (duration: 334.94µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:28:18.503711Z","caller":"traceutil/trace.go:171","msg":"trace[1660380379] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"567.030867ms","start":"2026-02-24T06:28:17.936644Z","end":"2026-02-24T06:28:18.503675Z","steps":["trace[1660380379] 'process raft request' (duration: 566.687687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:28:18.503788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.916414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:28:18.503851Z","caller":"traceutil/trace.go:171","msg":"trace[650297021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1827; }","duration":"297.034867ms","start":"2026-02-24T06:28:18.206803Z","end":"2026-02-24T06:28:18.503838Z","steps":["trace[650297021] 'agreement among raft nodes before linearized reading' (duration: 296.880963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:28:18.503902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:28:17.936619Z","time spent":"567.170639ms","remote":"127.0.0.1:46688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:28:24.967178Z","caller":"traceutil/trace.go:171","msg":"trace[923229168] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1947; }","duration":"140.708457ms","start":"2026-02-24T06:28:24.826423Z","end":"2026-02-24T06:28:24.967131Z","steps":["trace[923229168] 'read index received' (duration: 140.293637ms)","trace[923229168] 'applied index is now lower than readState.Index' (duration: 413.24µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:28:24.967279Z","caller":"traceutil/trace.go:171","msg":"trace[1434417679] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"183.895351ms","start":"2026-02-24T06:28:24.783346Z","end":"2026-02-24T06:28:24.967241Z","steps":["trace[1434417679] 'process raft request' (duration: 183.45318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:28:24.967523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.078986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-24T06:28:24.967625Z","caller":"traceutil/trace.go:171","msg":"trace[612681009] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1858; }","duration":"141.245281ms","start":"2026-02-24T06:28:24.826354Z","end":"2026-02-24T06:28:24.967599Z","steps":["trace[612681009] 'agreement among raft nodes before linearized reading' (duration: 140.948964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:28:25.187696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.910243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T06:28:25.187854Z","caller":"traceutil/trace.go:171","msg":"trace[1854132666] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"214.926129ms","start":"2026-02-24T06:28:24.972905Z","end":"2026-02-24T06:28:25.187831Z","steps":["trace[1854132666] 'process raft request' (duration: 71.77803ms)","trace[1854132666] 'compare' (duration: 142.775029ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:28:25.391795Z","caller":"traceutil/trace.go:171","msg":"trace[304323199] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"198.29839ms","start":"2026-02-24T06:28:25.19346Z","end":"2026-02-24T06:28:25.391758Z","steps":["trace[304323199] 'process raft request' (duration: 164.530564ms)","trace[304323199] 'compare' (duration: 33.590272ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:28:25.43085Z","caller":"traceutil/trace.go:171","msg":"trace[298992258] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1949; }","duration":"226.105378ms","start":"2026-02-24T06:28:25.204704Z","end":"2026-02-24T06:28:25.430809Z","steps":["trace[298992258] 'read index received' (duration: 153.193949ms)","trace[298992258] 'applied index is now lower than readState.Index' (duration: 72.910168ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:28:25.431047Z","caller":"traceutil/trace.go:171","msg":"trace[73608546] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"237.36757ms","start":"2026-02-24T06:28:25.193637Z","end":"2026-02-24T06:28:25.431005Z","steps":["trace[73608546] 'process raft request' (duration: 236.94904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:28:25.43112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.373695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:28:25.431161Z","caller":"traceutil/trace.go:171","msg":"trace[873157825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1861; }","duration":"226.501758ms","start":"2026-02-24T06:28:25.204648Z","end":"2026-02-24T06:28:25.43115Z","steps":["trace[873157825] 'agreement among raft nodes before linearized reading' (duration: 226.342704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:28:25.431385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.707653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-24T06:28:25.43152Z","caller":"traceutil/trace.go:171","msg":"trace[1332522192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1861; }","duration":"141.928328ms","start":"2026-02-24T06:28:25.28956Z","end":"2026-02-24T06:28:25.431489Z","steps":["trace[1332522192] 'agreement among raft nodes before linearized reading' (duration: 141.567998ms)"],"step_count":1}