{"level":"warn","ts":"2026-08-20T20:31:18.686735Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-20T20:31:18.686822Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-08-20T20:31:18.68812Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-20T20:31:18.688145Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-08-20T20:31:18.688262Z","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-08-20T20:31:18.689399Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-08-20T20:31:18.68958Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-08-20T20:31:18.695676Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.798667ms"} {"level":"info","ts":"2026-08-20T20:31:18.703305Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-08-20T20:31:18.703413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-08-20T20:31:18.703438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-08-20T20:31:18.70348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-20T20:31:18.703493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-08-20T20:31:18.703565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-08-20T20:31:18.711001Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-20T20:31:18.717154Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-20T20:31:18.718351Z","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-08-20T20:31:18.721733Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-20T20:31:18.722016Z","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-08-20T20:31:18.722164Z","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-08-20T20:31:18.722182Z","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-08-20T20:31:18.72214Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-20T20:31:18.724185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-08-20T20:31:18.724489Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-08-20T20:31:18.728686Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-08-20T20:31:18.728681Z","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-08-20T20:31:18.728775Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-08-20T20:31:18.729422Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-20T20:31:18.729538Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-20T20:31:18.904013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-08-20T20:31:18.904095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-20T20:31:18.904129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-08-20T20:31:18.904149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-08-20T20:31:18.90416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-08-20T20:31:18.904178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-08-20T20:31:18.904191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-08-20T20:31:18.905015Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T20:31:18.906172Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T20:31:18.906294Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T20:31:18.906354Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T20:31:18.906419Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T20:31:18.906433Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T20:31:18.906121Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-08-20T20:31:18.90699Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-20T20:31:18.908337Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-20T20:31:18.909619Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-08-20T20:31:18.910075Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-20T20:31:23.004008Z","caller":"traceutil/trace.go:171","msg":"trace[2067089290] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"186.383597ms","start":"2026-08-20T20:31:22.817605Z","end":"2026-08-20T20:31:23.003989Z","steps":["trace[2067089290] 'process raft request' (duration: 186.268285ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:23.150873Z","caller":"traceutil/trace.go:171","msg":"trace[80565271] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"143.810511ms","start":"2026-08-20T20:31:23.007041Z","end":"2026-08-20T20:31:23.150852Z","steps":["trace[80565271] 'process raft request' (duration: 70.972474ms)","trace[80565271] 'compare' (duration: 72.700996ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:31:23.401382Z","caller":"traceutil/trace.go:171","msg":"trace[1831220227] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"175.183801ms","start":"2026-08-20T20:31:23.226164Z","end":"2026-08-20T20:31:23.401348Z","steps":["trace[1831220227] 'process raft request' (duration: 115.29002ms)","trace[1831220227] 'compare' (duration: 59.737419ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:31:24.282553Z","caller":"traceutil/trace.go:171","msg":"trace[1690880685] linearizableReadLoop","detail":"{readStateIndex:276; appliedIndex:275; }","duration":"217.908124ms","start":"2026-08-20T20:31:24.064624Z","end":"2026-08-20T20:31:24.282532Z","steps":["trace[1690880685] 'read index received' (duration: 135.192293ms)","trace[1690880685] 'applied index is now lower than readState.Index' (duration: 82.714641ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:31:24.282765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.092147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-20T20:31:24.282724Z","caller":"traceutil/trace.go:171","msg":"trace[346324873] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"227.900324ms","start":"2026-08-20T20:31:24.054777Z","end":"2026-08-20T20:31:24.282678Z","steps":["trace[346324873] 'process raft request' (duration: 145.165362ms)","trace[346324873] 'compare' (duration: 82.452359ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:31:24.282828Z","caller":"traceutil/trace.go:171","msg":"trace[1160404288] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:270; }","duration":"218.233688ms","start":"2026-08-20T20:31:24.064583Z","end":"2026-08-20T20:31:24.282817Z","steps":["trace[1160404288] 'agreement among raft nodes before linearized reading' (duration: 218.058636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:24.488374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.654757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:31:24.488489Z","caller":"traceutil/trace.go:171","msg":"trace[1605896350] linearizableReadLoop","detail":"{readStateIndex:279; appliedIndex:278; }","duration":"138.240173ms","start":"2026-08-20T20:31:24.350233Z","end":"2026-08-20T20:31:24.488474Z","steps":["trace[1605896350] 'read index received' (duration: 32.914597ms)","trace[1605896350] 'applied index is now lower than readState.Index' (duration: 105.324136ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:31:24.488587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.343244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-08-20T20:31:24.488619Z","caller":"traceutil/trace.go:171","msg":"trace[1146512875] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:273; }","duration":"138.393544ms","start":"2026-08-20T20:31:24.350217Z","end":"2026-08-20T20:31:24.48861Z","steps":["trace[1146512875] 'agreement among raft nodes before linearized reading' (duration: 138.300423ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:24.488596Z","caller":"traceutil/trace.go:171","msg":"trace[773403796] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"138.875231ms","start":"2026-08-20T20:31:24.349692Z","end":"2026-08-20T20:31:24.488567Z","steps":["trace[773403796] 'process raft request' (duration: 33.510695ms)","trace[773403796] 'compare' (duration: 104.365483ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:31:25.44208Z","caller":"traceutil/trace.go:171","msg":"trace[1546411712] linearizableReadLoop","detail":"{readStateIndex:297; appliedIndex:296; }","duration":"126.665155ms","start":"2026-08-20T20:31:25.315389Z","end":"2026-08-20T20:31:25.442054Z","steps":["trace[1546411712] 'read index received' (duration: 27.995041ms)","trace[1546411712] 'applied index is now lower than readState.Index' (duration: 98.668994ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:31:25.442135Z","caller":"traceutil/trace.go:171","msg":"trace[1706758789] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"152.056063ms","start":"2026-08-20T20:31:25.290047Z","end":"2026-08-20T20:31:25.442103Z","steps":["trace[1706758789] 'process raft request' (duration: 53.223647ms)","trace[1706758789] 'compare' (duration: 98.517542ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:31:25.442314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.901519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:31:25.442412Z","caller":"traceutil/trace.go:171","msg":"trace[345713529] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:0; response_revision:291; }","duration":"127.05624ms","start":"2026-08-20T20:31:25.315333Z","end":"2026-08-20T20:31:25.442389Z","steps":["trace[345713529] 'agreement among raft nodes before linearized reading' (duration: 126.830487ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:25.767051Z","caller":"traceutil/trace.go:171","msg":"trace[1795665779] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"120.505155ms","start":"2026-08-20T20:31:25.646518Z","end":"2026-08-20T20:31:25.767023Z","steps":["trace[1795665779] 'process raft request' (duration: 41.84368ms)","trace[1795665779] 'compare' (duration: 78.537324ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:31:25.952018Z","caller":"traceutil/trace.go:171","msg":"trace[2142253358] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"305.052369ms","start":"2026-08-20T20:31:25.646946Z","end":"2026-08-20T20:31:25.951998Z","steps":["trace[2142253358] 'process raft request' (duration: 227.051082ms)","trace[2142253358] 'compare' (duration: 77.508011ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:31:25.952258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:25.646932Z","time spent":"305.13538ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:31:25.952342Z","caller":"traceutil/trace.go:171","msg":"trace[597632645] linearizableReadLoop","detail":"{readStateIndex:302; appliedIndex:300; }","duration":"288.112819ms","start":"2026-08-20T20:31:25.664222Z","end":"2026-08-20T20:31:25.952335Z","steps":["trace[597632645] 'read index received' (duration: 24.146851ms)","trace[597632645] 'applied index is now lower than readState.Index' (duration: 263.965128ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:31:25.95244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.22367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-20T20:31:25.952466Z","caller":"traceutil/trace.go:171","msg":"trace[910597596] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:297; }","duration":"288.277442ms","start":"2026-08-20T20:31:25.664182Z","end":"2026-08-20T20:31:25.952459Z","steps":["trace[910597596] 'agreement among raft nodes before linearized reading' (duration: 288.228841ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:26.187702Z","caller":"traceutil/trace.go:171","msg":"trace[681319391] linearizableReadLoop","detail":"{readStateIndex:304; appliedIndex:303; }","duration":"235.321539ms","start":"2026-08-20T20:31:25.95236Z","end":"2026-08-20T20:31:26.187682Z","steps":["trace[681319391] 'read index received' (duration: 233.400435ms)","trace[681319391] 'applied index is now lower than readState.Index' (duration: 1.920384ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:31:26.187818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.399777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:31:26.187854Z","caller":"traceutil/trace.go:171","msg":"trace[2031711341] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:297; }","duration":"478.480727ms","start":"2026-08-20T20:31:25.709353Z","end":"2026-08-20T20:31:26.187834Z","steps":["trace[2031711341] 'agreement among raft nodes before linearized reading' (duration: 478.416666ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:26.187882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:25.709332Z","time spent":"478.543639ms","remote":"127.0.0.1:54866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" "} {"level":"warn","ts":"2026-08-20T20:31:26.187896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.838346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:31:26.187887Z","caller":"traceutil/trace.go:171","msg":"trace[1681773546] transaction","detail":"{read_only:false; number_of_response:0; response_revision:297; }","duration":"240.311413ms","start":"2026-08-20T20:31:25.947559Z","end":"2026-08-20T20:31:26.18787Z","steps":["trace[1681773546] 'process raft request' (duration: 238.074725ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:26.187938Z","caller":"traceutil/trace.go:171","msg":"trace[1371858715] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:297; }","duration":"335.925667ms","start":"2026-08-20T20:31:25.852001Z","end":"2026-08-20T20:31:26.187927Z","steps":["trace[1371858715] 'agreement among raft nodes before linearized reading' (duration: 335.836156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:26.187963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:25.851983Z","time spent":"335.973808ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-08-20T20:31:26.188253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.027169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-08-20T20:31:26.188304Z","caller":"traceutil/trace.go:171","msg":"trace[1349762523] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:297; }","duration":"336.0898ms","start":"2026-08-20T20:31:25.852206Z","end":"2026-08-20T20:31:26.188296Z","steps":["trace[1349762523] 'agreement among raft nodes before linearized reading' (duration: 335.590243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:26.188331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:25.852202Z","time spent":"336.12367ms","remote":"127.0.0.1:54820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":686,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-08-20T20:31:26.194088Z","caller":"traceutil/trace.go:171","msg":"trace[2065721007] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"238.733363ms","start":"2026-08-20T20:31:25.955276Z","end":"2026-08-20T20:31:26.194009Z","steps":["trace[2065721007] 'process raft request' (duration: 237.435786ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:26.194118Z","caller":"traceutil/trace.go:171","msg":"trace[863996483] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"221.095485ms","start":"2026-08-20T20:31:25.973007Z","end":"2026-08-20T20:31:26.194103Z","steps":["trace[863996483] 'process raft request' (duration: 220.200312ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:26.19445Z","caller":"traceutil/trace.go:171","msg":"trace[926578675] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"226.176141ms","start":"2026-08-20T20:31:25.96804Z","end":"2026-08-20T20:31:26.194216Z","steps":["trace[926578675] 'process raft request' (duration: 224.773943ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:26.194853Z","caller":"traceutil/trace.go:171","msg":"trace[125617947] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"239.250369ms","start":"2026-08-20T20:31:25.955583Z","end":"2026-08-20T20:31:26.194833Z","steps":["trace[125617947] 'process raft request' (duration: 237.203612ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:26.195152Z","caller":"traceutil/trace.go:171","msg":"trace[137662211] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"226.023069ms","start":"2026-08-20T20:31:25.968919Z","end":"2026-08-20T20:31:26.194942Z","steps":["trace[137662211] 'process raft request' (duration: 223.945202ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:26.195337Z","caller":"traceutil/trace.go:171","msg":"trace[15055219] transaction","detail":"{read_only:false; number_of_response:0; response_revision:300; }","duration":"227.188754ms","start":"2026-08-20T20:31:25.96813Z","end":"2026-08-20T20:31:26.195318Z","steps":["trace[15055219] 'process raft request' (duration: 224.714572ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:26.196866Z","caller":"traceutil/trace.go:171","msg":"trace[117077065] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"225.31486ms","start":"2026-08-20T20:31:25.971528Z","end":"2026-08-20T20:31:26.196843Z","steps":["trace[117077065] 'process raft request' (duration: 221.365019ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:26.443443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.952791ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:31:26.443734Z","caller":"traceutil/trace.go:171","msg":"trace[1447081136] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"155.255147ms","start":"2026-08-20T20:31:26.288456Z","end":"2026-08-20T20:31:26.443711Z","steps":["trace[1447081136] 'process raft request' (duration: 155.192446ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:26.443957Z","caller":"traceutil/trace.go:171","msg":"trace[384428656] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"161.743861ms","start":"2026-08-20T20:31:26.282202Z","end":"2026-08-20T20:31:26.443945Z","steps":["trace[384428656] 'process raft request' (duration: 24.205872ms)","trace[384428656] 'compare' (duration: 136.79032ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:31:26.444185Z","caller":"traceutil/trace.go:171","msg":"trace[1967268961] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:316; }","duration":"156.405421ms","start":"2026-08-20T20:31:26.287746Z","end":"2026-08-20T20:31:26.444151Z","steps":["trace[1967268961] 'read index received' (duration: 18.67639ms)","trace[1967268961] 'applied index is now lower than readState.Index' (duration: 137.726901ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:31:26.44427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.512733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-08-20T20:31:26.444298Z","caller":"traceutil/trace.go:171","msg":"trace[19318151] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:310; }","duration":"156.571064ms","start":"2026-08-20T20:31:26.28772Z","end":"2026-08-20T20:31:26.444291Z","steps":["trace[19318151] 'agreement among raft nodes before linearized reading' (duration: 156.504243ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:26.444292Z","caller":"traceutil/trace.go:171","msg":"trace[1291826252] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"160.136289ms","start":"2026-08-20T20:31:26.284143Z","end":"2026-08-20T20:31:26.44428Z","steps":["trace[1291826252] 'process raft request' (duration: 159.42197ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:27.186045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.362136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:31:27.186141Z","caller":"traceutil/trace.go:171","msg":"trace[1061888076] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:0; response_revision:335; }","duration":"121.505127ms","start":"2026-08-20T20:31:27.064614Z","end":"2026-08-20T20:31:27.186119Z","steps":["trace[1061888076] 'range keys from in-memory index tree' (duration: 121.280144ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:36.070799Z","caller":"traceutil/trace.go:171","msg":"trace[591758505] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"412.372483ms","start":"2026-08-20T20:31:35.658407Z","end":"2026-08-20T20:31:36.07078Z","steps":["trace[591758505] 'read index received' (duration: 412.176891ms)","trace[591758505] 'applied index is now lower than readState.Index' (duration: 193.492µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:31:36.070859Z","caller":"traceutil/trace.go:171","msg":"trace[1049496453] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"450.511566ms","start":"2026-08-20T20:31:35.620332Z","end":"2026-08-20T20:31:36.070843Z","steps":["trace[1049496453] 'process raft request' (duration: 450.336294ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:36.070912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.489005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:31:36.07094Z","caller":"traceutil/trace.go:171","msg":"trace[1850476150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:413; }","duration":"412.556675ms","start":"2026-08-20T20:31:35.658372Z","end":"2026-08-20T20:31:36.070928Z","steps":["trace[1850476150] 'agreement among raft nodes before linearized reading' (duration: 412.504774ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:36.070973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:35.658357Z","time spent":"412.608685ms","remote":"127.0.0.1:54418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-20T20:31:36.070971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:35.620304Z","time spent":"450.592788ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:31:36.071033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.737518ms","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-08-20T20:31:36.071068Z","caller":"traceutil/trace.go:171","msg":"trace[221513386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:413; }","duration":"260.772349ms","start":"2026-08-20T20:31:35.810285Z","end":"2026-08-20T20:31:36.071058Z","steps":["trace[221513386] 'agreement among raft nodes before linearized reading' (duration: 260.711408ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:36.071208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.309684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4796"} {"level":"info","ts":"2026-08-20T20:31:36.071234Z","caller":"traceutil/trace.go:171","msg":"trace[1953647982] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:413; }","duration":"203.361806ms","start":"2026-08-20T20:31:35.867864Z","end":"2026-08-20T20:31:36.071226Z","steps":["trace[1953647982] 'agreement among raft nodes before linearized reading' (duration: 203.315725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:47.158216Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228334784099070,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-20T20:31:47.308185Z","caller":"traceutil/trace.go:171","msg":"trace[143198896] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"651.040921ms","start":"2026-08-20T20:31:46.657117Z","end":"2026-08-20T20:31:47.308158Z","steps":["trace[143198896] 'read index received' (duration: 650.871459ms)","trace[143198896] 'applied index is now lower than readState.Index' (duration: 168.542µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:31:47.308236Z","caller":"traceutil/trace.go:171","msg":"trace[1837928234] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"739.831084ms","start":"2026-08-20T20:31:46.568382Z","end":"2026-08-20T20:31:47.308213Z","steps":["trace[1837928234] 'process raft request' (duration: 739.648211ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:47.30836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:46.568358Z","time spent":"739.930475ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:31:47.308369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.224213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-20T20:31:47.308387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.603983ms","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-08-20T20:31:47.308456Z","caller":"traceutil/trace.go:171","msg":"trace[1624329671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:475; }","duration":"651.372144ms","start":"2026-08-20T20:31:46.657065Z","end":"2026-08-20T20:31:47.308437Z","steps":["trace[1624329671] 'agreement among raft nodes before linearized reading' (duration: 651.196632ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:47.308473Z","caller":"traceutil/trace.go:171","msg":"trace[516892015] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:475; }","duration":"387.747835ms","start":"2026-08-20T20:31:46.920706Z","end":"2026-08-20T20:31:47.308454Z","steps":["trace[516892015] 'agreement among raft nodes before linearized reading' (duration: 387.597504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:47.308501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:46.657046Z","time spent":"651.446526ms","remote":"127.0.0.1:54426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-20T20:31:47.308514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:46.920685Z","time spent":"387.819407ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-20T20:31:48.357436Z","caller":"traceutil/trace.go:171","msg":"trace[1276874536] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"172.660765ms","start":"2026-08-20T20:31:48.184751Z","end":"2026-08-20T20:31:48.357411Z","steps":["trace[1276874536] 'process raft request' (duration: 172.503274ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:48.369871Z","caller":"traceutil/trace.go:171","msg":"trace[777006850] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"183.574867ms","start":"2026-08-20T20:31:48.186263Z","end":"2026-08-20T20:31:48.369838Z","steps":["trace[777006850] 'process raft request' (duration: 183.367615ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:48.370134Z","caller":"traceutil/trace.go:171","msg":"trace[692263891] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:492; }","duration":"103.3114ms","start":"2026-08-20T20:31:48.266772Z","end":"2026-08-20T20:31:48.370083Z","steps":["trace[692263891] 'read index received' (duration: 90.589868ms)","trace[692263891] 'applied index is now lower than readState.Index' (duration: 12.719732ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:31:48.370151Z","caller":"traceutil/trace.go:171","msg":"trace[1737126208] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"183.06103ms","start":"2026-08-20T20:31:48.187074Z","end":"2026-08-20T20:31:48.370135Z","steps":["trace[1737126208] 'process raft request' (duration: 182.694325ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:48.370257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.464622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:31:48.370526Z","caller":"traceutil/trace.go:171","msg":"trace[877108579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:479; }","duration":"103.791246ms","start":"2026-08-20T20:31:48.266717Z","end":"2026-08-20T20:31:48.370508Z","steps":["trace[877108579] 'agreement among raft nodes before linearized reading' (duration: 103.449102ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:58.04654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.600894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:31:58.046643Z","caller":"traceutil/trace.go:171","msg":"trace[923896951] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"391.738345ms","start":"2026-08-20T20:31:57.654879Z","end":"2026-08-20T20:31:58.046618Z","steps":["trace[923896951] 'range keys from in-memory index tree' (duration: 391.503403ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:58.046705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:57.654867Z","time spent":"391.825016ms","remote":"127.0.0.1:54426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-20T20:31:58.050132Z","caller":"traceutil/trace.go:171","msg":"trace[1386543206] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"161.029487ms","start":"2026-08-20T20:31:57.889092Z","end":"2026-08-20T20:31:58.050121Z","steps":["trace[1386543206] 'read index received' (duration: 104.24508ms)","trace[1386543206] 'applied index is now lower than readState.Index' (duration: 56.783847ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:31:58.050199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.102798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:31:58.050247Z","caller":"traceutil/trace.go:171","msg":"trace[474736076] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:534; }","duration":"161.195579ms","start":"2026-08-20T20:31:57.889039Z","end":"2026-08-20T20:31:58.050235Z","steps":["trace[474736076] 'agreement among raft nodes before linearized reading' (duration: 161.114918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:58.921479Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228334784099209,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-20T20:31:59.378914Z","caller":"traceutil/trace.go:171","msg":"trace[1451482509] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"971.539473ms","start":"2026-08-20T20:31:58.407345Z","end":"2026-08-20T20:31:59.378884Z","steps":["trace[1451482509] 'process raft request' (duration: 971.299361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:59.379081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:58.407321Z","time spent":"971.679115ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:31:59.387559Z","caller":"traceutil/trace.go:171","msg":"trace[1657653781] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"925.194386ms","start":"2026-08-20T20:31:58.462329Z","end":"2026-08-20T20:31:59.387523Z","steps":["trace[1657653781] 'process raft request' (duration: 924.926293ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:59.387631Z","caller":"traceutil/trace.go:171","msg":"trace[1826262235] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"912.261147ms","start":"2026-08-20T20:31:58.475347Z","end":"2026-08-20T20:31:59.387608Z","steps":["trace[1826262235] 'process raft request' (duration: 911.973264ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:59.38762Z","caller":"traceutil/trace.go:171","msg":"trace[87384172] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"925.163886ms","start":"2026-08-20T20:31:58.462437Z","end":"2026-08-20T20:31:59.387601Z","steps":["trace[87384172] 'process raft request' (duration: 924.850423ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:59.387638Z","caller":"traceutil/trace.go:171","msg":"trace[1103681394] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"813.583565ms","start":"2026-08-20T20:31:58.574039Z","end":"2026-08-20T20:31:59.387622Z","steps":["trace[1103681394] 'process raft request' (duration: 813.383583ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:59.387684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:58.462317Z","time spent":"925.308107ms","remote":"127.0.0.1:54458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-20T20:31:59.387688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:58.475328Z","time spent":"912.327748ms","remote":"127.0.0.1:54458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-20T20:31:59.387705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:58.462431Z","time spent":"925.232617ms","remote":"127.0.0.1:54458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-20T20:31:59.387718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:58.574022Z","time spent":"813.656646ms","remote":"127.0.0.1:54458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T20:31:59.387784Z","caller":"traceutil/trace.go:171","msg":"trace[624921011] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"812.845996ms","start":"2026-08-20T20:31:58.574931Z","end":"2026-08-20T20:31:59.387777Z","steps":["trace[624921011] 'process raft request' (duration: 812.527793ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:59.38781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:58.574924Z","time spent":"812.873436ms","remote":"127.0.0.1:54458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T20:31:59.387823Z","caller":"traceutil/trace.go:171","msg":"trace[971627246] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"885.082383ms","start":"2026-08-20T20:31:58.502731Z","end":"2026-08-20T20:31:59.387814Z","steps":["trace[971627246] 'process raft request' (duration: 884.657448ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:59.387858Z","caller":"traceutil/trace.go:171","msg":"trace[16824433] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"747.919225ms","start":"2026-08-20T20:31:58.639934Z","end":"2026-08-20T20:31:59.387853Z","steps":["trace[16824433] 'process raft request' (duration: 747.562661ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:59.38788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:58.639915Z","time spent":"747.954715ms","remote":"127.0.0.1:54458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-20T20:31:59.38787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:58.502711Z","time spent":"885.133503ms","remote":"127.0.0.1:54458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T20:31:59.387987Z","caller":"traceutil/trace.go:171","msg":"trace[1187287659] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"927.586264ms","start":"2026-08-20T20:31:58.460389Z","end":"2026-08-20T20:31:59.387975Z","steps":["trace[1187287659] 'process raft request' (duration: 926.827696ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:59.387991Z","caller":"traceutil/trace.go:171","msg":"trace[293131832] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"968.113704ms","start":"2026-08-20T20:31:58.419862Z","end":"2026-08-20T20:31:59.387976Z","steps":["trace[293131832] 'process raft request' (duration: 967.231234ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:31:59.387573Z","caller":"traceutil/trace.go:171","msg":"trace[1248703437] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"906.659633ms","start":"2026-08-20T20:31:58.480891Z","end":"2026-08-20T20:31:59.38755Z","steps":["trace[1248703437] 'process raft request' (duration: 906.4629ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:59.388074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:58.48087Z","time spent":"907.179618ms","remote":"127.0.0.1:54458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-20T20:31:59.388062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:58.419836Z","time spent":"968.183795ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:31:59.38806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:58.460373Z","time spent":"927.640325ms","remote":"127.0.0.1:54458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T20:31:59.388363Z","caller":"traceutil/trace.go:171","msg":"trace[1513225858] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"742.992288ms","start":"2026-08-20T20:31:58.64536Z","end":"2026-08-20T20:31:59.388352Z","steps":["trace[1513225858] 'process raft request' (duration: 742.185759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:59.388461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:58.645332Z","time spent":"743.107799ms","remote":"127.0.0.1:54458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T20:31:59.388658Z","caller":"traceutil/trace.go:171","msg":"trace[174814034] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"728.813724ms","start":"2026-08-20T20:31:58.659822Z","end":"2026-08-20T20:31:59.388636Z","steps":["trace[174814034] 'process raft request' (duration: 728.570921ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:59.388758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:58.659799Z","time spent":"728.895174ms","remote":"127.0.0.1:54458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T20:31:59.388848Z","caller":"traceutil/trace.go:171","msg":"trace[2043472145] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"633.122316ms","start":"2026-08-20T20:31:58.755716Z","end":"2026-08-20T20:31:59.388838Z","steps":["trace[2043472145] 'process raft request' (duration: 632.829613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:59.388913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:58.755694Z","time spent":"633.177796ms","remote":"127.0.0.1:54458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-20T20:31:59.389247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.834971ms","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-08-20T20:31:59.389359Z","caller":"traceutil/trace.go:171","msg":"trace[1777821890] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:553; }","duration":"968.204544ms","start":"2026-08-20T20:31:58.420397Z","end":"2026-08-20T20:31:59.388601Z","steps":["trace[1777821890] 'read index received' (duration: 959.047229ms)","trace[1777821890] 'applied index is now lower than readState.Index' (duration: 9.156685ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:31:59.389494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.750277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:31:59.389534Z","caller":"traceutil/trace.go:171","msg":"trace[219347112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:548; }","duration":"718.845539ms","start":"2026-08-20T20:31:58.670676Z","end":"2026-08-20T20:31:59.389521Z","steps":["trace[219347112] 'agreement among raft nodes before linearized reading' (duration: 718.747657ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:59.39099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:58.670667Z","time spent":"720.303615ms","remote":"127.0.0.1:54426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-20T20:31:59.389283Z","caller":"traceutil/trace.go:171","msg":"trace[1501721329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:548; }","duration":"968.918862ms","start":"2026-08-20T20:31:58.420353Z","end":"2026-08-20T20:31:59.389272Z","steps":["trace[1501721329] 'agreement among raft nodes before linearized reading' (duration: 968.804331ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:31:59.391287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:31:58.420336Z","time spent":"970.871785ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-20T20:32:05.891167Z","caller":"traceutil/trace.go:171","msg":"trace[1184773586] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"394.07908ms","start":"2026-08-20T20:32:05.497049Z","end":"2026-08-20T20:32:05.891128Z","steps":["trace[1184773586] 'process raft request' (duration: 341.937917ms)","trace[1184773586] 'compare' (duration: 51.751788ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:32:05.891586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:05.497028Z","time spent":"394.318562ms","remote":"127.0.0.1:54916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T20:32:06.946623Z","caller":"traceutil/trace.go:171","msg":"trace[1591694489] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"941.599009ms","start":"2026-08-20T20:32:06.004996Z","end":"2026-08-20T20:32:06.946595Z","steps":["trace[1591694489] 'process raft request' (duration: 941.424737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:06.946773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:06.004976Z","time spent":"941.720811ms","remote":"127.0.0.1:54610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:32:07.774171Z","caller":"traceutil/trace.go:171","msg":"trace[629525726] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:670; }","duration":"1.11628959s","start":"2026-08-20T20:32:06.657859Z","end":"2026-08-20T20:32:07.774148Z","steps":["trace[629525726] 'read index received' (duration: 288.605533ms)","trace[629525726] 'applied index is now lower than readState.Index' (duration: 827.683347ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:07.774278Z","caller":"traceutil/trace.go:171","msg":"trace[1469713329] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.465781854s","start":"2026-08-20T20:32:06.308462Z","end":"2026-08-20T20:32:07.774244Z","steps":["trace[1469713329] 'process raft request' (duration: 1.465210077s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:07.774356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116474862s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:32:07.774421Z","caller":"traceutil/trace.go:171","msg":"trace[731516001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"1.116591394s","start":"2026-08-20T20:32:06.657812Z","end":"2026-08-20T20:32:07.774403Z","steps":["trace[731516001] 'agreement among raft nodes before linearized reading' (duration: 1.116451272s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:07.774449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:06.657791Z","time spent":"1.116653974s","remote":"127.0.0.1:54426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-20T20:32:07.774444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:06.308432Z","time spent":"1.465918236s","remote":"127.0.0.1:54522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:32:07.792608Z","caller":"traceutil/trace.go:171","msg":"trace[1074423695] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"207.238464ms","start":"2026-08-20T20:32:07.585296Z","end":"2026-08-20T20:32:07.792534Z","steps":["trace[1074423695] 'process raft request' (duration: 207.099593ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:07.850116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.072483ms","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-08-20T20:32:07.850217Z","caller":"traceutil/trace.go:171","msg":"trace[1599452924] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"397.205975ms","start":"2026-08-20T20:32:07.452971Z","end":"2026-08-20T20:32:07.850177Z","steps":["trace[1599452924] 'agreement among raft nodes before linearized reading' (duration: 397.013303ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:07.850245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:07.452948Z","time spent":"397.291466ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-20T20:32:07.850123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.662536ms","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-08-20T20:32:07.850284Z","caller":"traceutil/trace.go:171","msg":"trace[1302685019] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"394.854128ms","start":"2026-08-20T20:32:07.455416Z","end":"2026-08-20T20:32:07.85027Z","steps":["trace[1302685019] 'agreement among raft nodes before linearized reading' (duration: 394.611906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:07.850321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:07.455406Z","time spent":"394.907299ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-20T20:32:07.850483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.623836ms","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-08-20T20:32:07.850583Z","caller":"traceutil/trace.go:171","msg":"trace[1023260094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"402.753867ms","start":"2026-08-20T20:32:07.447805Z","end":"2026-08-20T20:32:07.850559Z","steps":["trace[1023260094] 'agreement among raft nodes before linearized reading' (duration: 402.239752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:07.850625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.370426ms","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-08-20T20:32:07.850641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:07.447752Z","time spent":"402.874549ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-20T20:32:07.850661Z","caller":"traceutil/trace.go:171","msg":"trace[580133505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"396.442006ms","start":"2026-08-20T20:32:07.454207Z","end":"2026-08-20T20:32:07.850649Z","steps":["trace[580133505] 'agreement among raft nodes before linearized reading' (duration: 395.755129ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:07.850695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:07.454186Z","time spent":"396.502237ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-20T20:32:09.526153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.626151046s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:32:09.526397Z","caller":"traceutil/trace.go:171","msg":"trace[1928362706] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:675; }","duration":"1.667391163s","start":"2026-08-20T20:32:07.858988Z","end":"2026-08-20T20:32:09.526379Z","steps":["trace[1928362706] 'read index received' (duration: 40.942393ms)","trace[1928362706] 'applied index is now lower than readState.Index' (duration: 1.62644751s)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:09.52648Z","caller":"traceutil/trace.go:171","msg":"trace[338836732] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"1.673139217s","start":"2026-08-20T20:32:07.85333Z","end":"2026-08-20T20:32:09.526469Z","steps":["trace[338836732] 'process raft request' (duration: 46.590416ms)","trace[338836732] 'compare' (duration: 1.625927684s)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:32:09.526536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:07.853312Z","time spent":"1.673191757s","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:32:09.526665Z","caller":"traceutil/trace.go:171","msg":"trace[1673645528] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"1.672814834s","start":"2026-08-20T20:32:07.853836Z","end":"2026-08-20T20:32:09.52665Z","steps":["trace[1673645528] 'process raft request' (duration: 1.672393639s)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:09.526651Z","caller":"traceutil/trace.go:171","msg":"trace[51907905] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"1.671926303s","start":"2026-08-20T20:32:07.854691Z","end":"2026-08-20T20:32:09.526618Z","steps":["trace[51907905] 'process raft request' (duration: 1.67165176s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:09.526698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.667703216s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-08-20T20:32:09.526711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.663910463s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:32:09.526726Z","caller":"traceutil/trace.go:171","msg":"trace[866349554] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"1.672740073s","start":"2026-08-20T20:32:07.853946Z","end":"2026-08-20T20:32:09.526686Z","steps":["trace[866349554] 'process raft request' (duration: 1.672338019s)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:09.526758Z","caller":"traceutil/trace.go:171","msg":"trace[1021125061] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:654; }","duration":"1.667779537s","start":"2026-08-20T20:32:07.858955Z","end":"2026-08-20T20:32:09.526735Z","steps":["trace[1021125061] 'agreement among raft nodes before linearized reading' (duration: 1.667678375s)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:09.526785Z","caller":"traceutil/trace.go:171","msg":"trace[310662929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"1.664021024s","start":"2026-08-20T20:32:07.862749Z","end":"2026-08-20T20:32:09.52677Z","steps":["trace[310662929] 'agreement among raft nodes before linearized reading' (duration: 1.663875032s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:09.526821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:07.854671Z","time spent":"1.672066626s","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:32:09.526833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:07.862737Z","time spent":"1.664088066s","remote":"127.0.0.1:54426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-20T20:32:09.526859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:07.853941Z","time spent":"1.672849584s","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:32:09.526796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:07.858941Z","time spent":"1.667847847s","remote":"127.0.0.1:54626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-08-20T20:32:09.526742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:07.85383Z","time spent":"1.672871014s","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:32:10.887098Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.193392818s","expected-duration":"1s"} {"level":"info","ts":"2026-08-20T20:32:10.88753Z","caller":"traceutil/trace.go:171","msg":"trace[6583411] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"1.344051115s","start":"2026-08-20T20:32:09.543457Z","end":"2026-08-20T20:32:10.887508Z","steps":["trace[6583411] 'process raft request' (duration: 1.343888573s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:10.887615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.442059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:32:10.887682Z","caller":"traceutil/trace.go:171","msg":"trace[1374621076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"229.573439ms","start":"2026-08-20T20:32:10.658097Z","end":"2026-08-20T20:32:10.88767Z","steps":["trace[1374621076] 'agreement among raft nodes before linearized reading' (duration: 229.455708ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:10.88751Z","caller":"traceutil/trace.go:171","msg":"trace[1355076820] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"229.317396ms","start":"2026-08-20T20:32:10.658171Z","end":"2026-08-20T20:32:10.887488Z","steps":["trace[1355076820] 'read index received' (duration: 229.124934ms)","trace[1355076820] 'applied index is now lower than readState.Index' (duration: 191.302µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:32:10.887626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:09.543439Z","time spent":"1.344133545s","remote":"127.0.0.1:54610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T20:32:11.792159Z","caller":"traceutil/trace.go:171","msg":"trace[659366598] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"260.894085ms","start":"2026-08-20T20:32:11.531238Z","end":"2026-08-20T20:32:11.792133Z","steps":["trace[659366598] 'read index received' (duration: 260.775204ms)","trace[659366598] 'applied index is now lower than readState.Index' (duration: 117.961µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:11.792202Z","caller":"traceutil/trace.go:171","msg":"trace[238074944] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"264.552486ms","start":"2026-08-20T20:32:11.527621Z","end":"2026-08-20T20:32:11.792173Z","steps":["trace[238074944] 'process raft request' (duration: 264.351154ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:11.792425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.141547ms","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-08-20T20:32:11.792431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.741221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-20T20:32:11.792494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.36726ms","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-08-20T20:32:11.792495Z","caller":"traceutil/trace.go:171","msg":"trace[1793978309] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:664; }","duration":"261.27992ms","start":"2026-08-20T20:32:11.531196Z","end":"2026-08-20T20:32:11.792476Z","steps":["trace[1793978309] 'agreement among raft nodes before linearized reading' (duration: 261.066077ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:11.792504Z","caller":"traceutil/trace.go:171","msg":"trace[1878232276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"134.851842ms","start":"2026-08-20T20:32:11.657634Z","end":"2026-08-20T20:32:11.792486Z","steps":["trace[1878232276] 'agreement among raft nodes before linearized reading' (duration: 134.7383ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:11.792537Z","caller":"traceutil/trace.go:171","msg":"trace[2042062698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:664; }","duration":"260.44052ms","start":"2026-08-20T20:32:11.532086Z","end":"2026-08-20T20:32:11.792527Z","steps":["trace[2042062698] 'agreement among raft nodes before linearized reading' (duration: 260.283708ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:11.792532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.182898ms","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-08-20T20:32:11.792543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.201368ms","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-08-20T20:32:11.792589Z","caller":"traceutil/trace.go:171","msg":"trace[209235121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:664; }","duration":"261.252669ms","start":"2026-08-20T20:32:11.531321Z","end":"2026-08-20T20:32:11.792574Z","steps":["trace[209235121] 'agreement among raft nodes before linearized reading' (duration: 261.103517ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:11.79262Z","caller":"traceutil/trace.go:171","msg":"trace[583613203] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:664; }","duration":"261.31047ms","start":"2026-08-20T20:32:11.531291Z","end":"2026-08-20T20:32:11.792602Z","steps":["trace[583613203] 'agreement among raft nodes before linearized reading' (duration: 261.027377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:12.225333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.941159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:32:12.225594Z","caller":"traceutil/trace.go:171","msg":"trace[1744840711] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:689; }","duration":"188.038164ms","start":"2026-08-20T20:32:12.037536Z","end":"2026-08-20T20:32:12.225574Z","steps":["trace[1744840711] 'read index received' (duration: 187.639599ms)","trace[1744840711] 'applied index is now lower than readState.Index' (duration: 397.415µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:32:12.225832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.288097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qdx6g\" ","response":"range_response_count:1 size:17247"} {"level":"info","ts":"2026-08-20T20:32:12.22581Z","caller":"traceutil/trace.go:171","msg":"trace[1900955618] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"428.975464ms","start":"2026-08-20T20:32:11.796794Z","end":"2026-08-20T20:32:12.225769Z","steps":["trace[1900955618] 'process raft request' (duration: 428.65452ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:12.225868Z","caller":"traceutil/trace.go:171","msg":"trace[1422230666] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qdx6g; range_end:; response_count:1; response_revision:668; }","duration":"188.353498ms","start":"2026-08-20T20:32:12.037503Z","end":"2026-08-20T20:32:12.225856Z","steps":["trace[1422230666] 'agreement among raft nodes before linearized reading' (duration: 188.126175ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:12.22598Z","caller":"traceutil/trace.go:171","msg":"trace[1548840513] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"429.49198ms","start":"2026-08-20T20:32:11.796464Z","end":"2026-08-20T20:32:12.225956Z","steps":["trace[1548840513] 'process raft request' (duration: 68.830072ms)","trace[1548840513] 'compare' (duration: 359.815927ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:12.225983Z","caller":"traceutil/trace.go:171","msg":"trace[1021057861] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"428.782711ms","start":"2026-08-20T20:32:11.797177Z","end":"2026-08-20T20:32:12.22596Z","steps":["trace[1021057861] 'process raft request' (duration: 428.361886ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:12.226003Z","caller":"traceutil/trace.go:171","msg":"trace[564230258] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"428.766791ms","start":"2026-08-20T20:32:11.797137Z","end":"2026-08-20T20:32:12.225904Z","steps":["trace[564230258] 'process raft request' (duration: 428.368287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:12.226032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:11.796787Z","time spent":"429.127475ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:32:12.226101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:11.796437Z","time spent":"429.59611ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:32:12.226108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:11.797172Z","time spent":"428.866572ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:32:12.226134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:11.797131Z","time spent":"428.938653ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:32:12.70667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.260399ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:32:12.706861Z","caller":"traceutil/trace.go:171","msg":"trace[1057645497] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:668; }","duration":"131.490501ms","start":"2026-08-20T20:32:12.575343Z","end":"2026-08-20T20:32:12.706834Z","steps":["trace[1057645497] 'range keys from in-memory index tree' (duration: 131.227198ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:12.706847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.118015ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:32:12.707229Z","caller":"traceutil/trace.go:171","msg":"trace[372978354] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"668.328845ms","start":"2026-08-20T20:32:12.03863Z","end":"2026-08-20T20:32:12.706959Z","steps":["trace[372978354] 'process raft request' (duration: 478.996298ms)","trace[372978354] 'compare' (duration: 188.911993ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:32:12.707299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:12.038621Z","time spent":"668.65453ms","remote":"127.0.0.1:54502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":776,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T20:32:12.794611Z","caller":"traceutil/trace.go:171","msg":"trace[648355063] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:693; }","duration":"362.344314ms","start":"2026-08-20T20:32:12.432235Z","end":"2026-08-20T20:32:12.794579Z","steps":["trace[648355063] 'read index received' (duration: 85.379186ms)","trace[648355063] 'applied index is now lower than readState.Index' (duration: 276.963828ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:12.794753Z","caller":"traceutil/trace.go:171","msg":"trace[498906730] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"556.00136ms","start":"2026-08-20T20:32:12.238705Z","end":"2026-08-20T20:32:12.794706Z","steps":["trace[498906730] 'process raft request' (duration: 555.626826ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:12.794914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.588949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:32:12.794957Z","caller":"traceutil/trace.go:171","msg":"trace[1200223443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:670; }","duration":"136.708219ms","start":"2026-08-20T20:32:12.658237Z","end":"2026-08-20T20:32:12.794945Z","steps":["trace[1200223443] 'agreement among raft nodes before linearized reading' (duration: 136.608668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:12.794952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.716618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-20T20:32:12.795011Z","caller":"traceutil/trace.go:171","msg":"trace[1462227298] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:670; }","duration":"362.816959ms","start":"2026-08-20T20:32:12.432179Z","end":"2026-08-20T20:32:12.794995Z","steps":["trace[1462227298] 'agreement among raft nodes before linearized reading' (duration: 362.591207ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:12.794963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:12.238676Z","time spent":"556.166001ms","remote":"127.0.0.1:54610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:32:12.795053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:12.432159Z","time spent":"362.883009ms","remote":"127.0.0.1:54444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.223\" "} {"level":"info","ts":"2026-08-20T20:32:14.222169Z","caller":"traceutil/trace.go:171","msg":"trace[756241489] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"145.782643ms","start":"2026-08-20T20:32:14.076363Z","end":"2026-08-20T20:32:14.222145Z","steps":["trace[756241489] 'process raft request' (duration: 145.646282ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:14.410588Z","caller":"traceutil/trace.go:171","msg":"trace[359775455] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"180.711414ms","start":"2026-08-20T20:32:14.22986Z","end":"2026-08-20T20:32:14.410571Z","steps":["trace[359775455] 'read index received' (duration: 173.597604ms)","trace[359775455] 'applied index is now lower than readState.Index' (duration: 7.11314ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:14.410635Z","caller":"traceutil/trace.go:171","msg":"trace[2084259149] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"324.08785ms","start":"2026-08-20T20:32:14.086536Z","end":"2026-08-20T20:32:14.410623Z","steps":["trace[2084259149] 'process raft request' (duration: 316.92157ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:14.410717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:14.08651Z","time spent":"324.148541ms","remote":"127.0.0.1:54610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17775,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:32:14.410744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.869425ms","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-08-20T20:32:14.410774Z","caller":"traceutil/trace.go:171","msg":"trace[1861417927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:676; }","duration":"180.899526ms","start":"2026-08-20T20:32:14.229865Z","end":"2026-08-20T20:32:14.410764Z","steps":["trace[1861417927] 'agreement among raft nodes before linearized reading' (duration: 180.796505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:14.410771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.5139ms","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-08-20T20:32:14.410829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.850094ms","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-08-20T20:32:14.410831Z","caller":"traceutil/trace.go:171","msg":"trace[1426395623] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:676; }","duration":"179.58709ms","start":"2026-08-20T20:32:14.231231Z","end":"2026-08-20T20:32:14.410818Z","steps":["trace[1426395623] 'agreement among raft nodes before linearized reading' (duration: 179.467869ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:14.410843Z","caller":"traceutil/trace.go:171","msg":"trace[1636854342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:676; }","duration":"180.904896ms","start":"2026-08-20T20:32:14.229933Z","end":"2026-08-20T20:32:14.410838Z","steps":["trace[1636854342] 'agreement among raft nodes before linearized reading' (duration: 180.860605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:14.410794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.902556ms","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-08-20T20:32:14.410883Z","caller":"traceutil/trace.go:171","msg":"trace[1823272078] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:676; }","duration":"181.040447ms","start":"2026-08-20T20:32:14.229825Z","end":"2026-08-20T20:32:14.410865Z","steps":["trace[1823272078] 'agreement among raft nodes before linearized reading' (duration: 180.822895ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:14.578853Z","caller":"traceutil/trace.go:171","msg":"trace[508945408] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"164.24677ms","start":"2026-08-20T20:32:14.414588Z","end":"2026-08-20T20:32:14.578835Z","steps":["trace[508945408] 'process raft request' (duration: 158.85024ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:14.57909Z","caller":"traceutil/trace.go:171","msg":"trace[1596772156] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"163.39247ms","start":"2026-08-20T20:32:14.415676Z","end":"2026-08-20T20:32:14.579068Z","steps":["trace[1596772156] 'process raft request' (duration: 163.123627ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:14.579788Z","caller":"traceutil/trace.go:171","msg":"trace[1243787127] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"164.012897ms","start":"2026-08-20T20:32:14.415764Z","end":"2026-08-20T20:32:14.579777Z","steps":["trace[1243787127] 'process raft request' (duration: 163.975916ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:14.579819Z","caller":"traceutil/trace.go:171","msg":"trace[2085774062] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"164.152509ms","start":"2026-08-20T20:32:14.415657Z","end":"2026-08-20T20:32:14.57981Z","steps":["trace[2085774062] 'process raft request' (duration: 164.017448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:16.351659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.479086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:32:16.351727Z","caller":"traceutil/trace.go:171","msg":"trace[1923981745] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:708; }","duration":"263.015057ms","start":"2026-08-20T20:32:16.0887Z","end":"2026-08-20T20:32:16.351715Z","steps":["trace[1923981745] 'read index received' (duration: 29.221µs)","trace[1923981745] 'applied index is now lower than readState.Index' (duration: 262.985066ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:16.351768Z","caller":"traceutil/trace.go:171","msg":"trace[1692415058] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"643.092349ms","start":"2026-08-20T20:32:15.708645Z","end":"2026-08-20T20:32:16.351737Z","steps":["trace[1692415058] 'process raft request' (duration: 23.488731ms)","trace[1692415058] 'compare' (duration: 619.254344ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:32:16.35186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.150218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qdx6g\" ","response":"range_response_count:1 size:17790"} {"level":"info","ts":"2026-08-20T20:32:16.351882Z","caller":"traceutil/trace.go:171","msg":"trace[716477538] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qdx6g; range_end:; response_count:1; response_revision:683; }","duration":"263.206019ms","start":"2026-08-20T20:32:16.088668Z","end":"2026-08-20T20:32:16.351874Z","steps":["trace[716477538] 'agreement among raft nodes before linearized reading' (duration: 263.074127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:16.351899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:15.708624Z","time spent":"643.21259ms","remote":"127.0.0.1:54502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T20:32:16.750475Z","caller":"traceutil/trace.go:171","msg":"trace[1426960018] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"161.922976ms","start":"2026-08-20T20:32:16.58852Z","end":"2026-08-20T20:32:16.750443Z","steps":["trace[1426960018] 'process raft request' (duration: 161.793904ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:16.750638Z","caller":"traceutil/trace.go:171","msg":"trace[1118621896] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"162.026597ms","start":"2026-08-20T20:32:16.588584Z","end":"2026-08-20T20:32:16.750611Z","steps":["trace[1118621896] 'process raft request' (duration: 161.766994ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:16.750871Z","caller":"traceutil/trace.go:171","msg":"trace[1698156261] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"162.397461ms","start":"2026-08-20T20:32:16.588444Z","end":"2026-08-20T20:32:16.750842Z","steps":["trace[1698156261] 'process raft request' (duration: 158.443258ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:16.8644Z","caller":"traceutil/trace.go:171","msg":"trace[1116924724] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:726; }","duration":"112.378708ms","start":"2026-08-20T20:32:16.751999Z","end":"2026-08-20T20:32:16.864378Z","steps":["trace[1116924724] 'read index received' (duration: 99.630616ms)","trace[1116924724] 'applied index is now lower than readState.Index' (duration: 12.747082ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:32:16.864551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.522699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:32:16.864647Z","caller":"traceutil/trace.go:171","msg":"trace[1729758846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:701; }","duration":"112.66139ms","start":"2026-08-20T20:32:16.751968Z","end":"2026-08-20T20:32:16.864629Z","steps":["trace[1729758846] 'agreement among raft nodes before linearized reading' (duration: 112.473888ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:16.865698Z","caller":"traceutil/trace.go:171","msg":"trace[1029680343] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"131.373147ms","start":"2026-08-20T20:32:16.734269Z","end":"2026-08-20T20:32:16.865643Z","steps":["trace[1029680343] 'process raft request' (duration: 117.445513ms)","trace[1029680343] 'compare' (duration: 12.538778ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:17.266096Z","caller":"traceutil/trace.go:171","msg":"trace[1505284498] linearizableReadLoop","detail":"{readStateIndex:731; appliedIndex:730; }","duration":"168.780117ms","start":"2026-08-20T20:32:17.097286Z","end":"2026-08-20T20:32:17.266067Z","steps":["trace[1505284498] 'read index received' (duration: 131.705078ms)","trace[1505284498] 'applied index is now lower than readState.Index' (duration: 37.073649ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:17.266144Z","caller":"traceutil/trace.go:171","msg":"trace[128433830] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"229.089135ms","start":"2026-08-20T20:32:17.037029Z","end":"2026-08-20T20:32:17.266119Z","steps":["trace[128433830] 'process raft request' (duration: 192.024505ms)","trace[128433830] 'compare' (duration: 36.865218ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:32:17.266377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.05945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qdx6g\" ","response":"range_response_count:1 size:17974"} {"level":"info","ts":"2026-08-20T20:32:17.26646Z","caller":"traceutil/trace.go:171","msg":"trace[126540003] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qdx6g; range_end:; response_count:1; response_revision:705; }","duration":"169.202231ms","start":"2026-08-20T20:32:17.097241Z","end":"2026-08-20T20:32:17.266443Z","steps":["trace[126540003] 'agreement among raft nodes before linearized reading' (duration: 168.920308ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:17.267594Z","caller":"traceutil/trace.go:171","msg":"trace[1889235689] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"133.394776ms","start":"2026-08-20T20:32:17.134183Z","end":"2026-08-20T20:32:17.267578Z","steps":["trace[1889235689] 'process raft request' (duration: 133.356485ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:17.267811Z","caller":"traceutil/trace.go:171","msg":"trace[926670434] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"169.404196ms","start":"2026-08-20T20:32:17.098352Z","end":"2026-08-20T20:32:17.267756Z","steps":["trace[926670434] 'process raft request' (duration: 169.113541ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:17.41822Z","caller":"traceutil/trace.go:171","msg":"trace[73835410] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"135.890143ms","start":"2026-08-20T20:32:17.282311Z","end":"2026-08-20T20:32:17.418201Z","steps":["trace[73835410] 'process raft request' (duration: 132.85383ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:17.426323Z","caller":"traceutil/trace.go:171","msg":"trace[1098046406] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"143.736191ms","start":"2026-08-20T20:32:17.282564Z","end":"2026-08-20T20:32:17.4263Z","steps":["trace[1098046406] 'process raft request' (duration: 143.319896ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:17.426466Z","caller":"traceutil/trace.go:171","msg":"trace[76758392] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"136.150116ms","start":"2026-08-20T20:32:17.290298Z","end":"2026-08-20T20:32:17.426449Z","steps":["trace[76758392] 'process raft request' (duration: 135.811603ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:17.876808Z","caller":"traceutil/trace.go:171","msg":"trace[353617595] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"109.40549ms","start":"2026-08-20T20:32:17.767366Z","end":"2026-08-20T20:32:17.876772Z","steps":["trace[353617595] 'process raft request' (duration: 76.683708ms)","trace[353617595] 'compare' (duration: 32.52106ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:27.294346Z","caller":"traceutil/trace.go:171","msg":"trace[1964401721] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:836; }","duration":"477.830682ms","start":"2026-08-20T20:32:26.816485Z","end":"2026-08-20T20:32:27.294315Z","steps":["trace[1964401721] 'read index received' (duration: 477.538939ms)","trace[1964401721] 'applied index is now lower than readState.Index' (duration: 290.133µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:32:27.294546Z","caller":"traceutil/trace.go:171","msg":"trace[381392384] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"483.547843ms","start":"2026-08-20T20:32:26.810855Z","end":"2026-08-20T20:32:27.294403Z","steps":["trace[381392384] 'process raft request' (duration: 483.25031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:27.294739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:26.810824Z","time spent":"483.831546ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:32:27.294949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.406408ms","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-08-20T20:32:27.29495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.673358ms","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-08-20T20:32:27.295045Z","caller":"traceutil/trace.go:171","msg":"trace[1775490051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:806; }","duration":"478.550849ms","start":"2026-08-20T20:32:26.816445Z","end":"2026-08-20T20:32:27.294996Z","steps":["trace[1775490051] 'agreement among raft nodes before linearized reading' (duration: 478.031393ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:27.295082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:26.816427Z","time spent":"478.64734ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-20T20:32:27.295144Z","caller":"traceutil/trace.go:171","msg":"trace[769155159] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:806; }","duration":"476.793869ms","start":"2026-08-20T20:32:26.818221Z","end":"2026-08-20T20:32:27.295015Z","steps":["trace[769155159] 'agreement among raft nodes before linearized reading' (duration: 476.392145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:27.295255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:26.818216Z","time spent":"477.009631ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-20T20:32:28.791622Z","caller":"traceutil/trace.go:171","msg":"trace[16952323] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:842; }","duration":"136.699101ms","start":"2026-08-20T20:32:28.654895Z","end":"2026-08-20T20:32:28.791594Z","steps":["trace[16952323] 'read index received' (duration: 136.438348ms)","trace[16952323] 'applied index is now lower than readState.Index' (duration: 259.893µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:32:28.79178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.863933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:32:28.791826Z","caller":"traceutil/trace.go:171","msg":"trace[561772797] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:811; }","duration":"136.938444ms","start":"2026-08-20T20:32:28.654881Z","end":"2026-08-20T20:32:28.79182Z","steps":["trace[561772797] 'agreement among raft nodes before linearized reading' (duration: 136.852323ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:28.791852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.870553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:32:28.791898Z","caller":"traceutil/trace.go:171","msg":"trace[978839718] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:811; }","duration":"136.975204ms","start":"2026-08-20T20:32:28.654912Z","end":"2026-08-20T20:32:28.791887Z","steps":["trace[978839718] 'agreement among raft nodes before linearized reading' (duration: 136.854943ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:28.791994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.317152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:32:28.792022Z","caller":"traceutil/trace.go:171","msg":"trace[1610629790] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:811; }","duration":"119.363643ms","start":"2026-08-20T20:32:28.672651Z","end":"2026-08-20T20:32:28.792014Z","steps":["trace[1610629790] 'agreement among raft nodes before linearized reading' (duration: 119.326432ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:28.792205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.309532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:32:28.792256Z","caller":"traceutil/trace.go:171","msg":"trace[506497714] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:811; }","duration":"119.363313ms","start":"2026-08-20T20:32:28.672879Z","end":"2026-08-20T20:32:28.792243Z","steps":["trace[506497714] 'agreement among raft nodes before linearized reading' (duration: 119.291242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:28.792285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.44795ms","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-08-20T20:32:28.792318Z","caller":"traceutil/trace.go:171","msg":"trace[618199757] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:811; }","duration":"126.500321ms","start":"2026-08-20T20:32:28.665809Z","end":"2026-08-20T20:32:28.79231Z","steps":["trace[618199757] 'agreement among raft nodes before linearized reading' (duration: 126.377069ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:28.792448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.756719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-20T20:32:28.792444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.726018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:32:28.792475Z","caller":"traceutil/trace.go:171","msg":"trace[1579971856] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:811; }","duration":"133.81764ms","start":"2026-08-20T20:32:28.65865Z","end":"2026-08-20T20:32:28.792467Z","steps":["trace[1579971856] 'agreement among raft nodes before linearized reading' (duration: 133.77433ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:28.792489Z","caller":"traceutil/trace.go:171","msg":"trace[1664930872] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:811; }","duration":"119.786488ms","start":"2026-08-20T20:32:28.67269Z","end":"2026-08-20T20:32:28.792477Z","steps":["trace[1664930872] 'agreement among raft nodes before linearized reading' (duration: 119.718738ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:28.791702Z","caller":"traceutil/trace.go:171","msg":"trace[171154065] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"142.326212ms","start":"2026-08-20T20:32:28.649356Z","end":"2026-08-20T20:32:28.791682Z","steps":["trace[171154065] 'process raft request' (duration: 142.053789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:31.752604Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228334784099825,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-20T20:32:32.252809Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228334784099825,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-20T20:32:32.664676Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.480380557s","expected-duration":"1s"} {"level":"info","ts":"2026-08-20T20:32:32.665279Z","caller":"traceutil/trace.go:171","msg":"trace[1187036405] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"1.481047973s","start":"2026-08-20T20:32:31.184206Z","end":"2026-08-20T20:32:32.665254Z","steps":["trace[1187036405] 'process raft request' (duration: 1.48082268s)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:32.665622Z","caller":"traceutil/trace.go:171","msg":"trace[1609968065] linearizableReadLoop","detail":"{readStateIndex:859; appliedIndex:857; }","duration":"1.41314572s","start":"2026-08-20T20:32:31.252458Z","end":"2026-08-20T20:32:32.665604Z","steps":["trace[1609968065] 'read index received' (duration: 1.412486374s)","trace[1609968065] 'applied index is now lower than readState.Index' (duration: 658.236µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:32:32.665627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:31.184191Z","time spent":"1.481194425s","remote":"127.0.0.1:54610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:32:32.665703Z","caller":"traceutil/trace.go:171","msg":"trace[171145519] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"613.912296ms","start":"2026-08-20T20:32:32.051784Z","end":"2026-08-20T20:32:32.665696Z","steps":["trace[171145519] 'process raft request' (duration: 613.724184ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:32.665793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:32.051755Z","time spent":"613.971597ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:32:32.665975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.413499374s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-20T20:32:32.666041Z","caller":"traceutil/trace.go:171","msg":"trace[260785417] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:827; }","duration":"1.413598005s","start":"2026-08-20T20:32:31.252428Z","end":"2026-08-20T20:32:32.666026Z","steps":["trace[260785417] 'agreement among raft nodes before linearized reading' (duration: 1.413485994s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:32.666098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:31.252414Z","time spent":"1.413661125s","remote":"127.0.0.1:54784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-20T20:32:32.666124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.247853329s","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-08-20T20:32:32.665993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.264435597s","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-08-20T20:32:32.666222Z","caller":"traceutil/trace.go:171","msg":"trace[1236955637] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:827; }","duration":"1.24798621s","start":"2026-08-20T20:32:31.418224Z","end":"2026-08-20T20:32:32.666211Z","steps":["trace[1236955637] 'agreement among raft nodes before linearized reading' (duration: 1.247786898s)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:32.666318Z","caller":"traceutil/trace.go:171","msg":"trace[1562081592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:827; }","duration":"1.264761311s","start":"2026-08-20T20:32:31.401546Z","end":"2026-08-20T20:32:32.666307Z","steps":["trace[1562081592] 'agreement among raft nodes before linearized reading' (duration: 1.264376747s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:32.666332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:31.418205Z","time spent":"1.248115522s","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-20T20:32:32.666359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:31.401506Z","time spent":"1.264841281s","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-20T20:32:32.666428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204173408s","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-08-20T20:32:32.66674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.959748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-20T20:32:32.666806Z","caller":"traceutil/trace.go:171","msg":"trace[1598913704] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:827; }","duration":"234.073619ms","start":"2026-08-20T20:32:32.432716Z","end":"2026-08-20T20:32:32.66679Z","steps":["trace[1598913704] 'agreement among raft nodes before linearized reading' (duration: 233.256149ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:32.666563Z","caller":"traceutil/trace.go:171","msg":"trace[1220975023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:827; }","duration":"1.204327259s","start":"2026-08-20T20:32:31.462192Z","end":"2026-08-20T20:32:32.666519Z","steps":["trace[1220975023] 'agreement among raft nodes before linearized reading' (duration: 1.203860894s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:32:32.668868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:32:31.462169Z","time spent":"1.206669694s","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-20T20:32:53.377011Z","caller":"traceutil/trace.go:171","msg":"trace[1434995988] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"113.089001ms","start":"2026-08-20T20:32:53.263898Z","end":"2026-08-20T20:32:53.376987Z","steps":["trace[1434995988] 'process raft request' (duration: 112.932278ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:57.01003Z","caller":"traceutil/trace.go:171","msg":"trace[1753879078] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"205.937671ms","start":"2026-08-20T20:32:56.804068Z","end":"2026-08-20T20:32:57.010006Z","steps":["trace[1753879078] 'process raft request' (duration: 205.757249ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:57.018943Z","caller":"traceutil/trace.go:171","msg":"trace[1788041000] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1068; }","duration":"186.035506ms","start":"2026-08-20T20:32:56.832883Z","end":"2026-08-20T20:32:57.018918Z","steps":["trace[1788041000] 'read index received' (duration: 176.886049ms)","trace[1788041000] 'applied index is now lower than readState.Index' (duration: 9.148417ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:32:57.019164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.263178ms","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-08-20T20:32:57.019208Z","caller":"traceutil/trace.go:171","msg":"trace[550277991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1032; }","duration":"186.350578ms","start":"2026-08-20T20:32:56.832844Z","end":"2026-08-20T20:32:57.019195Z","steps":["trace[550277991] 'agreement among raft nodes before linearized reading' (duration: 186.200777ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:57.019055Z","caller":"traceutil/trace.go:171","msg":"trace[230112731] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"211.841064ms","start":"2026-08-20T20:32:56.807196Z","end":"2026-08-20T20:32:57.019037Z","steps":["trace[230112731] 'process raft request' (duration: 211.596091ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:57.142362Z","caller":"traceutil/trace.go:171","msg":"trace[1289304407] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"119.38541ms","start":"2026-08-20T20:32:57.022953Z","end":"2026-08-20T20:32:57.142339Z","steps":["trace[1289304407] 'process raft request' (duration: 118.750663ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:32:57.142533Z","caller":"traceutil/trace.go:171","msg":"trace[1850387079] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"119.461611ms","start":"2026-08-20T20:32:57.022986Z","end":"2026-08-20T20:32:57.142448Z","steps":["trace[1850387079] 'process raft request' (duration: 119.31628ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:33:33.560453Z","caller":"traceutil/trace.go:171","msg":"trace[1818135129] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"111.625151ms","start":"2026-08-20T20:33:33.448799Z","end":"2026-08-20T20:33:33.560424Z","steps":["trace[1818135129] 'process raft request' (duration: 111.564889ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:33:33.560905Z","caller":"traceutil/trace.go:171","msg":"trace[1369264073] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"112.193492ms","start":"2026-08-20T20:33:33.448677Z","end":"2026-08-20T20:33:33.560871Z","steps":["trace[1369264073] 'process raft request' (duration: 111.501017ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:02.165078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.556365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:34:02.165416Z","caller":"traceutil/trace.go:171","msg":"trace[2087283479] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"328.28097ms","start":"2026-08-20T20:34:01.837084Z","end":"2026-08-20T20:34:02.165365Z","steps":["trace[2087283479] 'process raft request' (duration: 74.370213ms)","trace[2087283479] 'compare' (duration: 253.447202ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:34:02.165486Z","caller":"traceutil/trace.go:171","msg":"trace[514406102] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"303.268653ms","start":"2026-08-20T20:34:01.86217Z","end":"2026-08-20T20:34:02.165439Z","steps":["trace[514406102] 'process raft request' (duration: 303.006393ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:02.165566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:34:01.837061Z","time spent":"328.423985ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:34:02.165636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:34:01.862147Z","time spent":"303.407617ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:34:13.365088Z","caller":"traceutil/trace.go:171","msg":"trace[997300250] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"101.176801ms","start":"2026-08-20T20:34:13.26389Z","end":"2026-08-20T20:34:13.365067Z","steps":["trace[997300250] 'process raft request' (duration: 101.059067ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:13.551494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.572086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:34:13.551637Z","caller":"traceutil/trace.go:171","msg":"trace[1156968633] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1346; }","duration":"149.884115ms","start":"2026-08-20T20:34:13.401729Z","end":"2026-08-20T20:34:13.551613Z","steps":["trace[1156968633] 'read index received' (duration: 36.461µs)","trace[1156968633] 'applied index is now lower than readState.Index' (duration: 149.845644ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:34:13.551758Z","caller":"traceutil/trace.go:171","msg":"trace[1948028902] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"182.795878ms","start":"2026-08-20T20:34:13.368933Z","end":"2026-08-20T20:34:13.551729Z","steps":["trace[1948028902] 'process raft request' (duration: 31.883871ms)","trace[1948028902] 'compare' (duration: 150.392161ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:34:13.552072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.102091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1490"} {"level":"info","ts":"2026-08-20T20:34:13.552132Z","caller":"traceutil/trace.go:171","msg":"trace[154674323] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1291; }","duration":"150.428391ms","start":"2026-08-20T20:34:13.401688Z","end":"2026-08-20T20:34:13.552117Z","steps":["trace[154674323] 'agreement among raft nodes before linearized reading' (duration: 149.985268ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:34:13.736319Z","caller":"traceutil/trace.go:171","msg":"trace[211548180] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-920bb355-8ae7-45c4-bfa1-d86750e2cd2d; range_end:; response_count:1; response_revision:1296; }","duration":"100.027257ms","start":"2026-08-20T20:34:13.636263Z","end":"2026-08-20T20:34:13.73629Z","steps":["trace[211548180] 'agreement among raft nodes before linearized reading' (duration: 99.934884ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:34:13.736442Z","caller":"traceutil/trace.go:171","msg":"trace[639307977] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"103.603153ms","start":"2026-08-20T20:34:13.632813Z","end":"2026-08-20T20:34:13.736417Z","steps":["trace[639307977] 'process raft request' (duration: 102.990395ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:20.093633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.892094ms","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-08-20T20:34:20.093729Z","caller":"traceutil/trace.go:171","msg":"trace[1701712098] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1338; }","duration":"176.048058ms","start":"2026-08-20T20:34:19.917665Z","end":"2026-08-20T20:34:20.093713Z","steps":["trace[1701712098] 'range keys from in-memory index tree' (duration: 175.726909ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:20.093812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.299396ms","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-08-20T20:34:20.093926Z","caller":"traceutil/trace.go:171","msg":"trace[1047265303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1338; }","duration":"209.44246ms","start":"2026-08-20T20:34:19.884432Z","end":"2026-08-20T20:34:20.093875Z","steps":["trace[1047265303] 'range keys from in-memory index tree' (duration: 209.115621ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:34:20.406456Z","caller":"traceutil/trace.go:171","msg":"trace[17874476] linearizableReadLoop","detail":"{readStateIndex:1397; appliedIndex:1396; }","duration":"317.118364ms","start":"2026-08-20T20:34:20.089299Z","end":"2026-08-20T20:34:20.406418Z","steps":["trace[17874476] 'read index received' (duration: 316.97249ms)","trace[17874476] 'applied index is now lower than readState.Index' (duration: 144.694µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:34:20.406473Z","caller":"traceutil/trace.go:171","msg":"trace[44831740] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"364.381004ms","start":"2026-08-20T20:34:20.042053Z","end":"2026-08-20T20:34:20.406434Z","steps":["trace[44831740] 'process raft request' (duration: 364.141157ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:20.406633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.302039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-20T20:34:20.406673Z","caller":"traceutil/trace.go:171","msg":"trace[1923652527] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1339; }","duration":"317.396442ms","start":"2026-08-20T20:34:20.089266Z","end":"2026-08-20T20:34:20.406663Z","steps":["trace[1923652527] 'agreement among raft nodes before linearized reading' (duration: 317.240968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:20.406706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:34:20.089254Z","time spent":"317.446363ms","remote":"127.0.0.1:54458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-08-20T20:34:20.406971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:34:20.042028Z","time spent":"364.599199ms","remote":"127.0.0.1:54502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T20:34:20.410462Z","caller":"traceutil/trace.go:171","msg":"trace[28379510] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"136.608426ms","start":"2026-08-20T20:34:20.273843Z","end":"2026-08-20T20:34:20.410451Z","steps":["trace[28379510] 'process raft request' (duration: 136.541504ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:34:20.41056Z","caller":"traceutil/trace.go:171","msg":"trace[1852093619] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"312.992705ms","start":"2026-08-20T20:34:20.097559Z","end":"2026-08-20T20:34:20.410552Z","steps":["trace[1852093619] 'process raft request' (duration: 312.678716ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:34:20.410627Z","caller":"traceutil/trace.go:171","msg":"trace[397930290] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"312.892971ms","start":"2026-08-20T20:34:20.097727Z","end":"2026-08-20T20:34:20.41062Z","steps":["trace[397930290] 'process raft request' (duration: 312.625574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:20.410625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.110585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-08-20T20:34:20.410657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:34:20.097461Z","time spent":"313.122357ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:34:20.410665Z","caller":"traceutil/trace.go:171","msg":"trace[2146623642] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1342; }","duration":"258.204768ms","start":"2026-08-20T20:34:20.152449Z","end":"2026-08-20T20:34:20.410654Z","steps":["trace[2146623642] 'agreement among raft nodes before linearized reading' (duration: 258.105396ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:20.410672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:34:20.097715Z","time spent":"312.934112ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:34:20.410557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.129666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:63811"} {"level":"info","ts":"2026-08-20T20:34:20.410705Z","caller":"traceutil/trace.go:171","msg":"trace[777747766] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1342; }","duration":"258.314443ms","start":"2026-08-20T20:34:20.152379Z","end":"2026-08-20T20:34:20.410693Z","steps":["trace[777747766] 'agreement among raft nodes before linearized reading' (duration: 258.041794ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:34:20.820859Z","caller":"traceutil/trace.go:171","msg":"trace[1327619770] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"133.953419ms","start":"2026-08-20T20:34:20.686875Z","end":"2026-08-20T20:34:20.820829Z","steps":["trace[1327619770] 'process raft request' (duration: 133.782465ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:34:22.72654Z","caller":"traceutil/trace.go:171","msg":"trace[1806480293] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"310.391803ms","start":"2026-08-20T20:34:22.416088Z","end":"2026-08-20T20:34:22.72648Z","steps":["trace[1806480293] 'process raft request' (duration: 310.179327ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:22.726736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:34:22.416075Z","time spent":"310.553808ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:34:23.813036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.790798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:34:23.81318Z","caller":"traceutil/trace.go:171","msg":"trace[988951146] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1404; }","duration":"1.382580943s","start":"2026-08-20T20:34:22.430584Z","end":"2026-08-20T20:34:23.813164Z","steps":["trace[988951146] 'read index received' (duration: 295.782634ms)","trace[988951146] 'applied index is now lower than readState.Index' (duration: 1.086797379s)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:34:23.813254Z","caller":"traceutil/trace.go:171","msg":"trace[1876011091] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"1.394059692s","start":"2026-08-20T20:34:22.419185Z","end":"2026-08-20T20:34:23.813245Z","steps":["trace[1876011091] 'process raft request' (duration: 763.989556ms)","trace[1876011091] 'compare' (duration: 629.582392ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:34:23.813569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.382988214s","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-08-20T20:34:23.8251Z","caller":"traceutil/trace.go:171","msg":"trace[148142213] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1349; }","duration":"1.394526114s","start":"2026-08-20T20:34:22.430539Z","end":"2026-08-20T20:34:23.825065Z","steps":["trace[148142213] 'agreement among raft nodes before linearized reading' (duration: 1.382883841s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:23.82513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:34:22.419169Z","time spent":"1.4058786s","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:34:23.825147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:34:22.430519Z","time spent":"1.394616946s","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-20T20:34:23.813639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37514402s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-20T20:34:23.825278Z","caller":"traceutil/trace.go:171","msg":"trace[157195327] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:1349; }","duration":"1.386820523s","start":"2026-08-20T20:34:22.438431Z","end":"2026-08-20T20:34:23.825251Z","steps":["trace[157195327] 'agreement among raft nodes before linearized reading' (duration: 1.375075558s)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:34:23.813702Z","caller":"traceutil/trace.go:171","msg":"trace[669403154] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"1.39366141s","start":"2026-08-20T20:34:22.420019Z","end":"2026-08-20T20:34:23.81368Z","steps":["trace[669403154] 'process raft request' (duration: 1.393101055s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:23.825457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:34:22.420005Z","time spent":"1.405427076s","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:34:23.825446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:34:22.438411Z","time spent":"1.386992008s","remote":"127.0.0.1:54444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.223\" "} {"level":"warn","ts":"2026-08-20T20:34:23.813739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.156336586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:34:23.825527Z","caller":"traceutil/trace.go:171","msg":"trace[1600867445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1349; }","duration":"1.168152193s","start":"2026-08-20T20:34:22.657365Z","end":"2026-08-20T20:34:23.825518Z","steps":["trace[1600867445] 'agreement among raft nodes before linearized reading' (duration: 1.156338936s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:23.825553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:34:22.65735Z","time spent":"1.168197705s","remote":"127.0.0.1:54426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-20T20:34:23.813741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.285324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"info","ts":"2026-08-20T20:34:23.825597Z","caller":"traceutil/trace.go:171","msg":"trace[1170130801] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1349; }","duration":"249.163854ms","start":"2026-08-20T20:34:23.576419Z","end":"2026-08-20T20:34:23.825583Z","steps":["trace[1170130801] 'agreement among raft nodes before linearized reading' (duration: 237.262784ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:34:24.034945Z","caller":"traceutil/trace.go:171","msg":"trace[63884660] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1407; }","duration":"199.935113ms","start":"2026-08-20T20:34:23.834993Z","end":"2026-08-20T20:34:24.034928Z","steps":["trace[63884660] 'read index received' (duration: 115.822531ms)","trace[63884660] 'applied index is now lower than readState.Index' (duration: 84.111862ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:34:24.034994Z","caller":"traceutil/trace.go:171","msg":"trace[2140176428] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"205.183953ms","start":"2026-08-20T20:34:23.829787Z","end":"2026-08-20T20:34:24.034971Z","steps":["trace[2140176428] 'process raft request' (duration: 205.018729ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:24.035135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.100078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:34:24.035216Z","caller":"traceutil/trace.go:171","msg":"trace[1786006815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1350; }","duration":"200.210591ms","start":"2026-08-20T20:34:23.834962Z","end":"2026-08-20T20:34:24.035173Z","steps":["trace[1786006815] 'agreement among raft nodes before linearized reading' (duration: 200.082897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:24.04621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.370431ms","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-08-20T20:34:24.046268Z","caller":"traceutil/trace.go:171","msg":"trace[1682283038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1351; }","duration":"209.456044ms","start":"2026-08-20T20:34:23.836799Z","end":"2026-08-20T20:34:24.046255Z","steps":["trace[1682283038] 'agreement among raft nodes before linearized reading' (duration: 209.287069ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:24.046279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.861323ms","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-08-20T20:34:24.046358Z","caller":"traceutil/trace.go:171","msg":"trace[1497304181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1351; }","duration":"203.974858ms","start":"2026-08-20T20:34:23.842365Z","end":"2026-08-20T20:34:24.04634Z","steps":["trace[1497304181] 'agreement among raft nodes before linearized reading' (duration: 203.759052ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:34:30.189182Z","caller":"traceutil/trace.go:171","msg":"trace[609382587] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"108.602326ms","start":"2026-08-20T20:34:30.080554Z","end":"2026-08-20T20:34:30.189157Z","steps":["trace[609382587] 'process raft request' (duration: 108.443092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:31.15869Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228334784101145,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-20T20:34:31.299002Z","caller":"traceutil/trace.go:171","msg":"trace[2019907250] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"925.883875ms","start":"2026-08-20T20:34:30.373089Z","end":"2026-08-20T20:34:31.298973Z","steps":["trace[2019907250] 'process raft request' (duration: 925.6961ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:31.29916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:34:30.373069Z","time spent":"926.008269ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5719,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:34:31.306902Z","caller":"traceutil/trace.go:171","msg":"trace[236144241] linearizableReadLoop","detail":"{readStateIndex:1441; appliedIndex:1438; }","duration":"649.321586ms","start":"2026-08-20T20:34:30.657555Z","end":"2026-08-20T20:34:31.306876Z","steps":["trace[236144241] 'read index received' (duration: 641.361675ms)","trace[236144241] 'applied index is now lower than readState.Index' (duration: 7.958461ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:34:31.306925Z","caller":"traceutil/trace.go:171","msg":"trace[988165401] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"555.664684ms","start":"2026-08-20T20:34:30.75123Z","end":"2026-08-20T20:34:31.306894Z","steps":["trace[988165401] 'process raft request' (duration: 555.457688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:31.307069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:34:30.75118Z","time spent":"555.810119ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:34:31.307196Z","caller":"traceutil/trace.go:171","msg":"trace[812343101] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"463.082644ms","start":"2026-08-20T20:34:30.844095Z","end":"2026-08-20T20:34:31.307177Z","steps":["trace[812343101] 'process raft request' (duration: 462.715994ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:31.30731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:34:30.844075Z","time spent":"463.171016ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:34:31.307386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.513377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:34:31.307431Z","caller":"traceutil/trace.go:171","msg":"trace[449097585] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1381; }","duration":"489.624911ms","start":"2026-08-20T20:34:30.817795Z","end":"2026-08-20T20:34:31.307419Z","steps":["trace[449097585] 'agreement among raft nodes before linearized reading' (duration: 489.55296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:31.307466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:34:30.817775Z","time spent":"489.680852ms","remote":"127.0.0.1:54812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-08-20T20:34:31.307492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.923433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:34:31.307558Z","caller":"traceutil/trace.go:171","msg":"trace[1811501142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1381; }","duration":"650.063136ms","start":"2026-08-20T20:34:30.657481Z","end":"2026-08-20T20:34:31.307544Z","steps":["trace[1811501142] 'agreement among raft nodes before linearized reading' (duration: 649.933262ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:31.307588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:34:30.657463Z","time spent":"650.120348ms","remote":"127.0.0.1:54426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-20T20:34:44.254574Z","caller":"traceutil/trace.go:171","msg":"trace[661750510] linearizableReadLoop","detail":"{readStateIndex:1491; appliedIndex:1490; }","duration":"111.580069ms","start":"2026-08-20T20:34:44.142977Z","end":"2026-08-20T20:34:44.254557Z","steps":["trace[661750510] 'read index received' (duration: 111.428065ms)","trace[661750510] 'applied index is now lower than readState.Index' (duration: 151.514µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:34:44.254661Z","caller":"traceutil/trace.go:171","msg":"trace[1525266216] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"112.771639ms","start":"2026-08-20T20:34:44.14188Z","end":"2026-08-20T20:34:44.254652Z","steps":["trace[1525266216] 'process raft request' (duration: 112.572394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:44.255046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.02378ms","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-08-20T20:34:44.255173Z","caller":"traceutil/trace.go:171","msg":"trace[256753665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1428; }","duration":"112.215955ms","start":"2026-08-20T20:34:44.142925Z","end":"2026-08-20T20:34:44.255141Z","steps":["trace[256753665] 'agreement among raft nodes before linearized reading' (duration: 111.866086ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:34:44.410875Z","caller":"traceutil/trace.go:171","msg":"trace[1537323317] linearizableReadLoop","detail":"{readStateIndex:1493; appliedIndex:1492; }","duration":"149.365091ms","start":"2026-08-20T20:34:44.261481Z","end":"2026-08-20T20:34:44.410846Z","steps":["trace[1537323317] 'read index received' (duration: 58.251619ms)","trace[1537323317] 'applied index is now lower than readState.Index' (duration: 91.112172ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:34:44.411231Z","caller":"traceutil/trace.go:171","msg":"trace[793488798] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"149.942475ms","start":"2026-08-20T20:34:44.261274Z","end":"2026-08-20T20:34:44.411216Z","steps":["trace[793488798] 'process raft request' (duration: 85.821112ms)","trace[793488798] 'compare' (duration: 63.61058ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:34:44.41131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.803332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver\" ","response":"range_response_count:1 size:679"} {"level":"info","ts":"2026-08-20T20:34:44.411413Z","caller":"traceutil/trace.go:171","msg":"trace[2003567192] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver; range_end:; response_count:1; response_revision:1430; }","duration":"149.952416ms","start":"2026-08-20T20:34:44.261436Z","end":"2026-08-20T20:34:44.411388Z","steps":["trace[2003567192] 'agreement among raft nodes before linearized reading' (duration: 149.782081ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:34:46.457323Z","caller":"traceutil/trace.go:171","msg":"trace[1644797558] linearizableReadLoop","detail":"{readStateIndex:1498; appliedIndex:1497; }","duration":"134.414839ms","start":"2026-08-20T20:34:46.322885Z","end":"2026-08-20T20:34:46.4573Z","steps":["trace[1644797558] 'read index received' (duration: 134.207804ms)","trace[1644797558] 'applied index is now lower than readState.Index' (duration: 205.925µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:34:46.457384Z","caller":"traceutil/trace.go:171","msg":"trace[1696463286] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"195.12197ms","start":"2026-08-20T20:34:46.26223Z","end":"2026-08-20T20:34:46.457352Z","steps":["trace[1696463286] 'process raft request' (duration: 194.932365ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:46.457659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.838138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cluster-admin\" ","response":"range_response_count:1 size:679"} {"level":"info","ts":"2026-08-20T20:34:46.457704Z","caller":"traceutil/trace.go:171","msg":"trace[2029889268] range","detail":"{range_begin:/registry/clusterrolebindings/cluster-admin; range_end:; response_count:1; response_revision:1435; }","duration":"127.913939ms","start":"2026-08-20T20:34:46.329778Z","end":"2026-08-20T20:34:46.457692Z","steps":["trace[2029889268] 'agreement among raft nodes before linearized reading' (duration: 127.815827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:46.457758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.541602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pv-protection-controller\" ","response":"range_response_count:1 size:693"} {"level":"info","ts":"2026-08-20T20:34:46.457815Z","caller":"traceutil/trace.go:171","msg":"trace[1975294254] range","detail":"{range_begin:/registry/clusterroles/system:controller:pv-protection-controller; range_end:; response_count:1; response_revision:1435; }","duration":"109.618374ms","start":"2026-08-20T20:34:46.34818Z","end":"2026-08-20T20:34:46.457798Z","steps":["trace[1975294254] 'agreement among raft nodes before linearized reading' (duration: 109.519342ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:34:46.458304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.304504ms","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-08-20T20:34:46.458376Z","caller":"traceutil/trace.go:171","msg":"trace[1968137411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1435; }","duration":"135.531779ms","start":"2026-08-20T20:34:46.322819Z","end":"2026-08-20T20:34:46.45835Z","steps":["trace[1968137411] 'agreement among raft nodes before linearized reading' (duration: 134.627645ms)"],"step_count":1}