{"level":"warn","ts":"2026-02-25T01:40:06.668162Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T01:40:06.668432Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132: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.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-25T01:40:06.668529Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T01:40:06.668548Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-02-25T01:40:06.668569Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-25T01:40:06.669426Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-02-25T01:40:06.66952Z","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.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-25T01:40:06.673988Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.169857ms"} {"level":"info","ts":"2026-02-25T01:40:06.679844Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-02-25T01:40:06.679947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T01:40:06.679975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-02-25T01:40:06.679993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T01:40:06.680009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-02-25T01:40:06.680047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-02-25T01:40:06.687155Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T01:40:06.691079Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T01:40:06.69183Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-25T01:40:06.693712Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T01:40:06.69499Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T01:40:06.695065Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T01:40:06.694874Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T01:40:06.696845Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T01:40:06.699457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-02-25T01:40:06.69961Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-02-25T01:40:06.701496Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-25T01:40:06.701911Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T01:40:06.701965Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T01:40:06.702093Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-02-25T01:40:06.702105Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-02-25T01:40:07.180576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T01:40:07.180665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T01:40:07.180689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-02-25T01:40:07.180709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-02-25T01:40:07.180726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-02-25T01:40:07.180746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-02-25T01:40:07.180764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-02-25T01:40:07.181532Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:40:07.182244Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T01:40:07.182261Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T01:40:07.182563Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T01:40:07.18263Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T01:40:07.182735Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:40:07.182848Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:40:07.182881Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:40:07.183973Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T01:40:07.185611Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T01:40:07.187944Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-02-25T01:40:13.08753Z","caller":"traceutil/trace.go:171","msg":"trace[2104417874] transaction","detail":"{read_only:false; number_of_response:0; response_revision:274; }","duration":"110.495646ms","start":"2026-02-25T01:40:12.977001Z","end":"2026-02-25T01:40:13.087496Z","steps":["trace[2104417874] 'process raft request' (duration: 72.334147ms)","trace[2104417874] 'compare' (duration: 38.113198ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:40:13.088213Z","caller":"traceutil/trace.go:171","msg":"trace[1582350511] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"107.016845ms","start":"2026-02-25T01:40:12.981181Z","end":"2026-02-25T01:40:13.088197Z","steps":["trace[1582350511] 'process raft request' (duration: 106.862471ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:13.296528Z","caller":"traceutil/trace.go:171","msg":"trace[1298507916] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:290; }","duration":"112.427451ms","start":"2026-02-25T01:40:13.184078Z","end":"2026-02-25T01:40:13.296506Z","steps":["trace[1298507916] 'read index received' (duration: 16.444823ms)","trace[1298507916] 'applied index is now lower than readState.Index' (duration: 95.981828ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:40:13.296646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.548364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:40:13.29669Z","caller":"traceutil/trace.go:171","msg":"trace[970019146] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:0; response_revision:280; }","duration":"112.652757ms","start":"2026-02-25T01:40:13.184025Z","end":"2026-02-25T01:40:13.296678Z","steps":["trace[970019146] 'agreement among raft nodes before linearized reading' (duration: 112.564585ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:13.296734Z","caller":"traceutil/trace.go:171","msg":"trace[91050369] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"142.147285ms","start":"2026-02-25T01:40:13.154576Z","end":"2026-02-25T01:40:13.296723Z","steps":["trace[91050369] 'process raft request' (duration: 45.977653ms)","trace[91050369] 'compare' (duration: 95.844024ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:40:37.331326Z","caller":"traceutil/trace.go:171","msg":"trace[1300563661] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:490; }","duration":"494.205724ms","start":"2026-02-25T01:40:36.837095Z","end":"2026-02-25T01:40:37.331301Z","steps":["trace[1300563661] 'read index received' (duration: 494.081261ms)","trace[1300563661] 'applied index is now lower than readState.Index' (duration: 123.673µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:40:37.331421Z","caller":"traceutil/trace.go:171","msg":"trace[916280222] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"590.953549ms","start":"2026-02-25T01:40:36.740462Z","end":"2026-02-25T01:40:37.331416Z","steps":["trace[916280222] 'process raft request' (duration: 590.718874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:37.33167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.124523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:40:37.331745Z","caller":"traceutil/trace.go:171","msg":"trace[245483403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:475; }","duration":"422.230845ms","start":"2026-02-25T01:40:36.909494Z","end":"2026-02-25T01:40:37.331725Z","steps":["trace[245483403] 'agreement among raft nodes before linearized reading' (duration: 422.122203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:37.331744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:36.740425Z","time spent":"591.012221ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:40:37.331791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:36.909479Z","time spent":"422.304257ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:40:37.332026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.93036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T01:40:37.332038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.739812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T01:40:37.332061Z","caller":"traceutil/trace.go:171","msg":"trace[514897451] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:475; }","duration":"495.051123ms","start":"2026-02-25T01:40:36.837Z","end":"2026-02-25T01:40:37.332051Z","steps":["trace[514897451] 'agreement among raft nodes before linearized reading' (duration: 494.990421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:37.332096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:36.836985Z","time spent":"495.104284ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T01:40:37.332107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.857057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T01:40:37.332149Z","caller":"traceutil/trace.go:171","msg":"trace[835036326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:475; }","duration":"129.926129ms","start":"2026-02-25T01:40:37.202216Z","end":"2026-02-25T01:40:37.332143Z","steps":["trace[835036326] 'agreement among raft nodes before linearized reading' (duration: 129.868978ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:37.332067Z","caller":"traceutil/trace.go:171","msg":"trace[375055548] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:475; }","duration":"279.857445ms","start":"2026-02-25T01:40:37.052201Z","end":"2026-02-25T01:40:37.332058Z","steps":["trace[375055548] 'agreement among raft nodes before linearized reading' (duration: 279.809694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:43.855471Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256877331586718790,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T01:40:44.020381Z","caller":"traceutil/trace.go:171","msg":"trace[1192261870] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"665.367045ms","start":"2026-02-25T01:40:43.354982Z","end":"2026-02-25T01:40:44.020349Z","steps":["trace[1192261870] 'read index received' (duration: 665.088798ms)","trace[1192261870] 'applied index is now lower than readState.Index' (duration: 277.187µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:40:44.02056Z","caller":"traceutil/trace.go:171","msg":"trace[1799219290] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"938.660267ms","start":"2026-02-25T01:40:43.081762Z","end":"2026-02-25T01:40:44.020422Z","steps":["trace[1799219290] 'process raft request' (duration: 938.376089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:44.020713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.01285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T01:40:44.020609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.602951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T01:40:44.020775Z","caller":"traceutil/trace.go:171","msg":"trace[868420335] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:494; }","duration":"651.101382ms","start":"2026-02-25T01:40:43.369657Z","end":"2026-02-25T01:40:44.020759Z","steps":["trace[868420335] 'agreement among raft nodes before linearized reading' (duration: 650.994989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:44.020734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:43.081727Z","time spent":"938.932813ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:40:44.02082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:43.369645Z","time spent":"651.167084ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T01:40:44.020845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.689943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:40:44.020849Z","caller":"traceutil/trace.go:171","msg":"trace[1007696830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:494; }","duration":"665.865326ms","start":"2026-02-25T01:40:43.35495Z","end":"2026-02-25T01:40:44.020816Z","steps":["trace[1007696830] 'agreement among raft nodes before linearized reading' (duration: 665.533298ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:44.020929Z","caller":"traceutil/trace.go:171","msg":"trace[520554064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:494; }","duration":"653.814025ms","start":"2026-02-25T01:40:43.367095Z","end":"2026-02-25T01:40:44.020909Z","steps":["trace[520554064] 'agreement among raft nodes before linearized reading' (duration: 653.59463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:44.020982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:43.367079Z","time spent":"653.894368ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T01:40:44.020983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:43.354936Z","time spent":"666.00405ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T01:40:44.021422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.311933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T01:40:44.021485Z","caller":"traceutil/trace.go:171","msg":"trace[287323780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:494; }","duration":"656.415625ms","start":"2026-02-25T01:40:43.365054Z","end":"2026-02-25T01:40:44.02147Z","steps":["trace[287323780] 'agreement among raft nodes before linearized reading' (duration: 655.595516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:44.021525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:43.365032Z","time spent":"656.483037ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T01:40:44.256543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.791896ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:40:44.256739Z","caller":"traceutil/trace.go:171","msg":"trace[1738928628] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"230.75257ms","start":"2026-02-25T01:40:44.025967Z","end":"2026-02-25T01:40:44.25672Z","steps":["trace[1738928628] 'process raft request' (duration: 67.539955ms)","trace[1738928628] 'compare' (duration: 162.659583ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:40:44.577433Z","caller":"traceutil/trace.go:171","msg":"trace[1010622696] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:512; }","duration":"115.151365ms","start":"2026-02-25T01:40:44.46226Z","end":"2026-02-25T01:40:44.577411Z","steps":["trace[1010622696] 'read index received' (duration: 57.026331ms)","trace[1010622696] 'applied index is now lower than readState.Index' (duration: 58.123974ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:40:44.577586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.312689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:40:44.577623Z","caller":"traceutil/trace.go:171","msg":"trace[495589010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:498; }","duration":"115.398451ms","start":"2026-02-25T01:40:44.462214Z","end":"2026-02-25T01:40:44.577612Z","steps":["trace[495589010] 'agreement among raft nodes before linearized reading' (duration: 115.291718ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:44.577835Z","caller":"traceutil/trace.go:171","msg":"trace[609931098] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"551.10032ms","start":"2026-02-25T01:40:44.026696Z","end":"2026-02-25T01:40:44.577796Z","steps":["trace[609931098] 'process raft request' (duration: 550.672899ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:44.577884Z","caller":"traceutil/trace.go:171","msg":"trace[1240149703] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"551.478169ms","start":"2026-02-25T01:40:44.026381Z","end":"2026-02-25T01:40:44.577859Z","steps":["trace[1240149703] 'process raft request' (duration: 492.921233ms)","trace[1240149703] 'compare' (duration: 57.631023ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:40:44.577884Z","caller":"traceutil/trace.go:171","msg":"trace[587969913] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"551.240574ms","start":"2026-02-25T01:40:44.026619Z","end":"2026-02-25T01:40:44.577859Z","steps":["trace[587969913] 'process raft request' (duration: 550.669209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:44.578026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:44.026687Z","time spent":"551.244173ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:40:44.578135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:44.026607Z","time spent":"551.444638ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:40:44.577999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:44.026349Z","time spent":"551.585751ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:40:46.340202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.414673ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T01:40:46.340294Z","caller":"traceutil/trace.go:171","msg":"trace[2038594930] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:515; }","duration":"877.774117ms","start":"2026-02-25T01:40:45.462503Z","end":"2026-02-25T01:40:46.340277Z","steps":["trace[2038594930] 'read index received' (duration: 466.231811ms)","trace[2038594930] 'applied index is now lower than readState.Index' (duration: 411.541076ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:40:46.340424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.918261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:40:46.340495Z","caller":"traceutil/trace.go:171","msg":"trace[241745462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:498; }","duration":"878.029753ms","start":"2026-02-25T01:40:45.462457Z","end":"2026-02-25T01:40:46.340486Z","steps":["trace[241745462] 'agreement among raft nodes before linearized reading' (duration: 877.91689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:46.340531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:45.462438Z","time spent":"878.084635ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:40:48.496908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.769799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T01:40:48.497001Z","caller":"traceutil/trace.go:171","msg":"trace[44312637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:530; }","duration":"118.905963ms","start":"2026-02-25T01:40:48.378074Z","end":"2026-02-25T01:40:48.49698Z","steps":["trace[44312637] 'range keys from in-memory index tree' (duration: 118.623576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:52.448256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.016076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:40:52.44854Z","caller":"traceutil/trace.go:171","msg":"trace[768401909] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"643.892583ms","start":"2026-02-25T01:40:51.804591Z","end":"2026-02-25T01:40:52.448484Z","steps":["trace[768401909] 'process raft request' (duration: 521.467969ms)","trace[768401909] 'compare' (duration: 121.482923ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:40:52.448624Z","caller":"traceutil/trace.go:171","msg":"trace[990101256] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"445.290573ms","start":"2026-02-25T01:40:52.003319Z","end":"2026-02-25T01:40:52.44861Z","steps":["trace[990101256] 'read index received' (duration: 322.48625ms)","trace[990101256] 'applied index is now lower than readState.Index' (duration: 122.802493ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:40:52.448755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:51.804547Z","time spent":"644.087188ms","remote":"127.0.0.1:41974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:40:52.44856Z","caller":"traceutil/trace.go:171","msg":"trace[720014068] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"270.548777ms","start":"2026-02-25T01:40:52.17798Z","end":"2026-02-25T01:40:52.448529Z","steps":["trace[720014068] 'process raft request' (duration: 270.455565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:52.448852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.532058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-25T01:40:52.448889Z","caller":"traceutil/trace.go:171","msg":"trace[654792101] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:578; }","duration":"445.59758ms","start":"2026-02-25T01:40:52.003282Z","end":"2026-02-25T01:40:52.44888Z","steps":["trace[654792101] 'agreement among raft nodes before linearized reading' (duration: 445.426226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:52.448931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:52.003247Z","time spent":"445.674261ms","remote":"127.0.0.1:41974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-25T01:40:52.449029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.698222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-25T01:40:52.449078Z","caller":"traceutil/trace.go:171","msg":"trace[1620823034] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:578; }","duration":"246.779174ms","start":"2026-02-25T01:40:52.202285Z","end":"2026-02-25T01:40:52.449064Z","steps":["trace[1620823034] 'agreement among raft nodes before linearized reading' (duration: 246.6194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:52.449014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.60558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T01:40:52.449202Z","caller":"traceutil/trace.go:171","msg":"trace[1708970277] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:578; }","duration":"430.855106ms","start":"2026-02-25T01:40:52.018336Z","end":"2026-02-25T01:40:52.449191Z","steps":["trace[1708970277] 'agreement among raft nodes before linearized reading' (duration: 430.450436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:52.4492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.249407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2fv48\" ","response":"range_response_count:1 size:17918"} {"level":"warn","ts":"2026-02-25T01:40:52.449244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:52.018316Z","time spent":"430.918276ms","remote":"127.0.0.1:42216","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":"info","ts":"2026-02-25T01:40:52.449292Z","caller":"traceutil/trace.go:171","msg":"trace[885611451] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2fv48; range_end:; response_count:1; response_revision:578; }","duration":"272.35204ms","start":"2026-02-25T01:40:52.176893Z","end":"2026-02-25T01:40:52.449245Z","steps":["trace[885611451] 'agreement among raft nodes before linearized reading' (duration: 272.208046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:52.718073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.144742ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:40:52.718172Z","caller":"traceutil/trace.go:171","msg":"trace[368565247] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"215.630358ms","start":"2026-02-25T01:40:52.502532Z","end":"2026-02-25T01:40:52.718163Z","steps":["trace[368565247] 'read index received' (duration: 106.495564ms)","trace[368565247] 'applied index is now lower than readState.Index' (duration: 109.133894ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:40:52.718376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.812633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:40:52.718485Z","caller":"traceutil/trace.go:171","msg":"trace[603620393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"215.967725ms","start":"2026-02-25T01:40:52.502498Z","end":"2026-02-25T01:40:52.718466Z","steps":["trace[603620393] 'agreement among raft nodes before linearized reading' (duration: 215.784741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:52.718525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.581373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:40:52.718512Z","caller":"traceutil/trace.go:171","msg":"trace[412559853] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"238.507632ms","start":"2026-02-25T01:40:52.479975Z","end":"2026-02-25T01:40:52.718483Z","steps":["trace[412559853] 'process raft request' (duration: 129.108271ms)","trace[412559853] 'compare' (duration: 107.576869ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:40:52.718611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.367751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T01:40:52.71863Z","caller":"traceutil/trace.go:171","msg":"trace[1460267905] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"103.436172ms","start":"2026-02-25T01:40:52.61519Z","end":"2026-02-25T01:40:52.718626Z","steps":["trace[1460267905] 'agreement among raft nodes before linearized reading' (duration: 103.37161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:52.718698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.598843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T01:40:52.718551Z","caller":"traceutil/trace.go:171","msg":"trace[983588453] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"196.653475ms","start":"2026-02-25T01:40:52.521891Z","end":"2026-02-25T01:40:52.718545Z","steps":["trace[983588453] 'agreement among raft nodes before linearized reading' (duration: 196.472271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:52.718805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.05827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:40:52.718821Z","caller":"traceutil/trace.go:171","msg":"trace[1019248168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"105.124722ms","start":"2026-02-25T01:40:52.613692Z","end":"2026-02-25T01:40:52.718816Z","steps":["trace[1019248168] 'agreement among raft nodes before linearized reading' (duration: 105.07537ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:52.71881Z","caller":"traceutil/trace.go:171","msg":"trace[114571949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"102.757667ms","start":"2026-02-25T01:40:52.61603Z","end":"2026-02-25T01:40:52.718787Z","steps":["trace[114571949] 'agreement among raft nodes before linearized reading' (duration: 102.392857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:52.720296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.288795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-02-25T01:40:52.72034Z","caller":"traceutil/trace.go:171","msg":"trace[1027674248] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:580; }","duration":"117.343376ms","start":"2026-02-25T01:40:52.602974Z","end":"2026-02-25T01:40:52.720318Z","steps":["trace[1027674248] 'agreement among raft nodes before linearized reading' (duration: 115.905633ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:59.399802Z","caller":"traceutil/trace.go:171","msg":"trace[1060884642] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"176.881946ms","start":"2026-02-25T01:40:59.222895Z","end":"2026-02-25T01:40:59.399777Z","steps":["trace[1060884642] 'process raft request' (duration: 176.685611ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:05.230079Z","caller":"traceutil/trace.go:171","msg":"trace[280614322] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:702; }","duration":"422.633864ms","start":"2026-02-25T01:41:04.807414Z","end":"2026-02-25T01:41:05.230048Z","steps":["trace[280614322] 'read index received' (duration: 422.523061ms)","trace[280614322] 'applied index is now lower than readState.Index' (duration: 109.563µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:05.230304Z","caller":"traceutil/trace.go:171","msg":"trace[1027212445] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"605.30445ms","start":"2026-02-25T01:41:04.624952Z","end":"2026-02-25T01:41:05.230257Z","steps":["trace[1027212445] 'process raft request' (duration: 604.943581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:05.230517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.093255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:41:05.230629Z","caller":"traceutil/trace.go:171","msg":"trace[541645865] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:681; }","duration":"423.246518ms","start":"2026-02-25T01:41:04.807363Z","end":"2026-02-25T01:41:05.230609Z","steps":["trace[541645865] 'agreement among raft nodes before linearized reading' (duration: 422.952521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:05.230515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.060091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T01:41:05.230741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:04.807341Z","time spent":"423.382042ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T01:41:05.230822Z","caller":"traceutil/trace.go:171","msg":"trace[1589230920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:681; }","duration":"371.39676ms","start":"2026-02-25T01:41:04.859405Z","end":"2026-02-25T01:41:05.230802Z","steps":["trace[1589230920] 'agreement among raft nodes before linearized reading' (duration: 371.020941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:05.230515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.334113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-25T01:41:05.231052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:04.859394Z","time spent":"371.639505ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T01:41:05.230533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.267319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T01:41:05.231128Z","caller":"traceutil/trace.go:171","msg":"trace[156454902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:681; }","duration":"371.003019ms","start":"2026-02-25T01:41:04.860104Z","end":"2026-02-25T01:41:05.231107Z","steps":["trace[156454902] 'agreement among raft nodes before linearized reading' (duration: 370.296772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:05.23119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:04.860084Z","time spent":"371.091121ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T01:41:05.231206Z","caller":"traceutil/trace.go:171","msg":"trace[805916851] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:681; }","duration":"409.983756ms","start":"2026-02-25T01:41:04.821188Z","end":"2026-02-25T01:41:05.231172Z","steps":["trace[805916851] 'agreement among raft nodes before linearized reading' (duration: 409.214367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:05.230514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:04.624917Z","time spent":"605.493135ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:41:05.231267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:04.821177Z","time spent":"410.073039ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T01:41:05.385108Z","caller":"traceutil/trace.go:171","msg":"trace[2015641769] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"149.023738ms","start":"2026-02-25T01:41:05.236059Z","end":"2026-02-25T01:41:05.385083Z","steps":["trace[2015641769] 'process raft request' (duration: 88.974931ms)","trace[2015641769] 'compare' (duration: 59.938555ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:05.456999Z","caller":"traceutil/trace.go:171","msg":"trace[718508587] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"220.734061ms","start":"2026-02-25T01:41:05.236245Z","end":"2026-02-25T01:41:05.456979Z","steps":["trace[718508587] 'process raft request' (duration: 220.589017ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:05.45725Z","caller":"traceutil/trace.go:171","msg":"trace[1388149313] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"220.963386ms","start":"2026-02-25T01:41:05.236269Z","end":"2026-02-25T01:41:05.457233Z","steps":["trace[1388149313] 'process raft request' (duration: 220.674869ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:05.457505Z","caller":"traceutil/trace.go:171","msg":"trace[200430764] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"221.075009ms","start":"2026-02-25T01:41:05.23641Z","end":"2026-02-25T01:41:05.457485Z","steps":["trace[200430764] 'process raft request' (duration: 220.755601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:05.964146Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256877331586719141,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:41:06.669818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.377371ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T01:41:06.669935Z","caller":"traceutil/trace.go:171","msg":"trace[1147825296] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:707; }","duration":"1.206166192s","start":"2026-02-25T01:41:05.463749Z","end":"2026-02-25T01:41:06.669915Z","steps":["trace[1147825296] 'read index received' (duration: 600.634627ms)","trace[1147825296] 'applied index is now lower than readState.Index' (duration: 605.529515ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:06.67003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.206260724s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:41:06.670073Z","caller":"traceutil/trace.go:171","msg":"trace[966171843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:685; }","duration":"1.206342836s","start":"2026-02-25T01:41:05.463719Z","end":"2026-02-25T01:41:06.670062Z","steps":["trace[966171843] 'agreement among raft nodes before linearized reading' (duration: 1.206252794s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:06.670112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:05.463707Z","time spent":"1.206396807s","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T01:41:07.488615Z","caller":"traceutil/trace.go:171","msg":"trace[1773452557] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"150.481075ms","start":"2026-02-25T01:41:07.338076Z","end":"2026-02-25T01:41:07.488557Z","steps":["trace[1773452557] 'process raft request' (duration: 150.352412ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:08.590834Z","caller":"traceutil/trace.go:171","msg":"trace[1530885960] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"1.097926013s","start":"2026-02-25T01:41:07.492889Z","end":"2026-02-25T01:41:08.590815Z","steps":["trace[1530885960] 'process raft request' (duration: 1.097754499s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:08.591038Z","caller":"traceutil/trace.go:171","msg":"trace[1822529020] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"1.098098187s","start":"2026-02-25T01:41:07.492913Z","end":"2026-02-25T01:41:08.591011Z","steps":["trace[1822529020] 'process raft request' (duration: 1.097872091s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:08.591057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:07.492872Z","time spent":"1.098094047s","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:41:08.59114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:07.492872Z","time spent":"1.098214279s","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:08.591349Z","caller":"traceutil/trace.go:171","msg":"trace[1650233688] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:711; }","duration":"124.993595ms","start":"2026-02-25T01:41:08.466342Z","end":"2026-02-25T01:41:08.591335Z","steps":["trace[1650233688] 'read index received' (duration: 124.261787ms)","trace[1650233688] 'applied index is now lower than readState.Index' (duration: 693.117µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:08.591474Z","caller":"traceutil/trace.go:171","msg":"trace[1658543293] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"1.098453506s","start":"2026-02-25T01:41:07.493012Z","end":"2026-02-25T01:41:08.591465Z","steps":["trace[1658543293] 'process raft request' (duration: 1.097952214s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:08.591535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:07.492989Z","time spent":"1.098514097s","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:41:08.591733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.393296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:41:08.591766Z","caller":"traceutil/trace.go:171","msg":"trace[190994042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:691; }","duration":"125.430786ms","start":"2026-02-25T01:41:08.466326Z","end":"2026-02-25T01:41:08.591757Z","steps":["trace[190994042] 'agreement among raft nodes before linearized reading' (duration: 125.384955ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:09.50278Z","caller":"traceutil/trace.go:171","msg":"trace[1518979923] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"227.735901ms","start":"2026-02-25T01:41:09.275012Z","end":"2026-02-25T01:41:09.502748Z","steps":["trace[1518979923] 'process raft request' (duration: 227.416503ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:16.734384Z","caller":"traceutil/trace.go:171","msg":"trace[1771079216] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"106.24714ms","start":"2026-02-25T01:41:16.628118Z","end":"2026-02-25T01:41:16.734365Z","steps":["trace[1771079216] 'process raft request' (duration: 106.087056ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:16.766176Z","caller":"traceutil/trace.go:171","msg":"trace[2133157935] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"136.767322ms","start":"2026-02-25T01:41:16.629387Z","end":"2026-02-25T01:41:16.766154Z","steps":["trace[2133157935] 'process raft request' (duration: 136.724601ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:16.766266Z","caller":"traceutil/trace.go:171","msg":"trace[115614981] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"136.964336ms","start":"2026-02-25T01:41:16.62927Z","end":"2026-02-25T01:41:16.766235Z","steps":["trace[115614981] 'process raft request' (duration: 136.731101ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:35.888912Z","caller":"traceutil/trace.go:171","msg":"trace[1556979441] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"179.318185ms","start":"2026-02-25T01:41:35.709568Z","end":"2026-02-25T01:41:35.888887Z","steps":["trace[1556979441] 'read index received' (duration: 179.079979ms)","trace[1556979441] 'applied index is now lower than readState.Index' (duration: 234.006µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:35.888975Z","caller":"traceutil/trace.go:171","msg":"trace[340647386] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"265.412115ms","start":"2026-02-25T01:41:35.623542Z","end":"2026-02-25T01:41:35.888954Z","steps":["trace[340647386] 'process raft request' (duration: 265.17728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:35.8893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.682693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:41:35.889433Z","caller":"traceutil/trace.go:171","msg":"trace[1097307180] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:772; }","duration":"179.863577ms","start":"2026-02-25T01:41:35.709539Z","end":"2026-02-25T01:41:35.889402Z","steps":["trace[1097307180] 'agreement among raft nodes before linearized reading' (duration: 179.450567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:38.962857Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256877331586719418,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:41:39.463669Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256877331586719418,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:41:39.484963Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.293848183s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T01:41:39.485362Z","caller":"traceutil/trace.go:171","msg":"trace[124291492] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"1.294350986s","start":"2026-02-25T01:41:38.190993Z","end":"2026-02-25T01:41:39.485344Z","steps":["trace[124291492] 'process raft request' (duration: 1.294216182s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:39.48549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:38.190977Z","time spent":"1.294444627s","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:41:39.830012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.894153ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:41:39.830186Z","caller":"traceutil/trace.go:171","msg":"trace[1097585072] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:806; }","duration":"1.368073727s","start":"2026-02-25T01:41:38.462088Z","end":"2026-02-25T01:41:39.830161Z","steps":["trace[1097585072] 'read index received' (duration: 1.023142341s)","trace[1097585072] 'applied index is now lower than readState.Index' (duration: 344.929236ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:39.83028Z","caller":"traceutil/trace.go:171","msg":"trace[1566036622] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"1.639088397s","start":"2026-02-25T01:41:38.191152Z","end":"2026-02-25T01:41:39.830241Z","steps":["trace[1566036622] 'process raft request' (duration: 1.415579999s)","trace[1566036622] 'compare' (duration: 222.597565ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:39.830587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.368282982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:41:39.830646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.506349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T01:41:39.830654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:38.19114Z","time spent":"1.639241261s","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:39.830668Z","caller":"traceutil/trace.go:171","msg":"trace[1126749776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"1.3685999s","start":"2026-02-25T01:41:38.462047Z","end":"2026-02-25T01:41:39.830647Z","steps":["trace[1126749776] 'agreement among raft nodes before linearized reading' (duration: 1.368242531s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:39.830682Z","caller":"traceutil/trace.go:171","msg":"trace[1845457965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:778; }","duration":"963.58344ms","start":"2026-02-25T01:41:38.867086Z","end":"2026-02-25T01:41:39.830669Z","steps":["trace[1845457965] 'agreement among raft nodes before linearized reading' (duration: 963.205781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:39.830702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.193316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T01:41:39.830717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:38.462031Z","time spent":"1.368676761s","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:41:39.830726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:38.867027Z","time spent":"963.690023ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T01:41:39.830727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.953861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:41:39.830794Z","caller":"traceutil/trace.go:171","msg":"trace[496433155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:778; }","duration":"871.090704ms","start":"2026-02-25T01:41:38.959686Z","end":"2026-02-25T01:41:39.830777Z","steps":["trace[496433155] 'agreement among raft nodes before linearized reading' (duration: 870.662304ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:39.830749Z","caller":"traceutil/trace.go:171","msg":"trace[592833839] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:778; }","duration":"871.287779ms","start":"2026-02-25T01:41:38.959449Z","end":"2026-02-25T01:41:39.830737Z","steps":["trace[592833839] 'agreement among raft nodes before linearized reading' (duration: 871.179967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:39.830877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:38.959407Z","time spent":"871.464233ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T01:41:39.830881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:38.959667Z","time spent":"871.199636ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T01:41:40.266502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.234442ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:41:40.266782Z","caller":"traceutil/trace.go:171","msg":"trace[890093338] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:808; }","duration":"426.382495ms","start":"2026-02-25T01:41:39.840381Z","end":"2026-02-25T01:41:40.266763Z","steps":["trace[890093338] 'read index received' (duration: 218.815584ms)","trace[890093338] 'applied index is now lower than readState.Index' (duration: 207.565851ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:40.266791Z","caller":"traceutil/trace.go:171","msg":"trace[1934949286] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"430.465045ms","start":"2026-02-25T01:41:39.83628Z","end":"2026-02-25T01:41:40.266745Z","steps":["trace[1934949286] 'process raft request' (duration: 222.890224ms)","trace[1934949286] 'compare' (duration: 206.919894ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:40.26701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:39.836252Z","time spent":"430.633668ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:40.26707Z","caller":"traceutil/trace.go:171","msg":"trace[1466032308] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"430.510795ms","start":"2026-02-25T01:41:39.836548Z","end":"2026-02-25T01:41:40.267058Z","steps":["trace[1466032308] 'process raft request' (duration: 430.064095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:40.267149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:39.836535Z","time spent":"430.566656ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:40.267298Z","caller":"traceutil/trace.go:171","msg":"trace[1273881835] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"430.485625ms","start":"2026-02-25T01:41:39.836804Z","end":"2026-02-25T01:41:40.267289Z","steps":["trace[1273881835] 'process raft request' (duration: 429.917621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:40.267345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:39.836794Z","time spent":"430.524175ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:41:40.267533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.144663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:41:40.267589Z","caller":"traceutil/trace.go:171","msg":"trace[1439543005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"427.225456ms","start":"2026-02-25T01:41:39.840348Z","end":"2026-02-25T01:41:40.267573Z","steps":["trace[1439543005] 'agreement among raft nodes before linearized reading' (duration: 427.141494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:40.26763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:39.840337Z","time spent":"427.283917ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:41:40.269324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.11902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T01:41:40.269411Z","caller":"traceutil/trace.go:171","msg":"trace[1913882201] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:781; }","duration":"109.335405ms","start":"2026-02-25T01:41:40.160044Z","end":"2026-02-25T01:41:40.26938Z","steps":["trace[1913882201] 'agreement among raft nodes before linearized reading' (duration: 107.268394ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:40.506378Z","caller":"traceutil/trace.go:171","msg":"trace[1362082821] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"188.861576ms","start":"2026-02-25T01:41:40.317476Z","end":"2026-02-25T01:41:40.506338Z","steps":["trace[1362082821] 'process raft request' (duration: 151.069389ms)","trace[1362082821] 'compare' (duration: 37.372677ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:41.543488Z","caller":"traceutil/trace.go:171","msg":"trace[348106356] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"396.726524ms","start":"2026-02-25T01:41:41.146722Z","end":"2026-02-25T01:41:41.543449Z","steps":["trace[348106356] 'process raft request' (duration: 396.114659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:41.543596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:41.146697Z","time spent":"396.839797ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:41:41.980488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.353046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:41:41.980626Z","caller":"traceutil/trace.go:171","msg":"trace[1114945168] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:814; }","duration":"140.857281ms","start":"2026-02-25T01:41:41.83975Z","end":"2026-02-25T01:41:41.980607Z","steps":["trace[1114945168] 'read index received' (duration: 41.481µs)","trace[1114945168] 'applied index is now lower than readState.Index' (duration: 140.81409ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:41.980777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.020655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T01:41:41.980757Z","caller":"traceutil/trace.go:171","msg":"trace[415172661] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"432.493163ms","start":"2026-02-25T01:41:41.548222Z","end":"2026-02-25T01:41:41.980716Z","steps":["trace[415172661] 'process raft request' (duration: 84.82645ms)","trace[415172661] 'compare' (duration: 347.223222ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:41.980968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:41.548206Z","time spent":"432.642838ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:41.98167Z","caller":"traceutil/trace.go:171","msg":"trace[823272993] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:784; }","duration":"141.101208ms","start":"2026-02-25T01:41:41.839706Z","end":"2026-02-25T01:41:41.980808Z","steps":["trace[823272993] 'agreement among raft nodes before linearized reading' (duration: 140.953423ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:42.581553Z","caller":"traceutil/trace.go:171","msg":"trace[1610523248] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:816; }","duration":"287.821289ms","start":"2026-02-25T01:41:42.293705Z","end":"2026-02-25T01:41:42.581526Z","steps":["trace[1610523248] 'read index received' (duration: 58.950691ms)","trace[1610523248] 'applied index is now lower than readState.Index' (duration: 228.869608ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:42.581707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.974444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:41:42.58176Z","caller":"traceutil/trace.go:171","msg":"trace[183746912] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:788; }","duration":"288.084446ms","start":"2026-02-25T01:41:42.293659Z","end":"2026-02-25T01:41:42.581744Z","steps":["trace[183746912] 'agreement among raft nodes before linearized reading' (duration: 287.969804ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:42.58161Z","caller":"traceutil/trace.go:171","msg":"trace[2026950174] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"302.323962ms","start":"2026-02-25T01:41:42.279241Z","end":"2026-02-25T01:41:42.581565Z","steps":["trace[2026950174] 'process raft request' (duration: 282.382228ms)","trace[2026950174] 'compare' (duration: 19.673738ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:42.582899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:42.279215Z","time spent":"303.553531ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:42.581804Z","caller":"traceutil/trace.go:171","msg":"trace[898336786] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"299.874942ms","start":"2026-02-25T01:41:42.281839Z","end":"2026-02-25T01:41:42.581714Z","steps":["trace[898336786] 'process raft request' (duration: 299.642596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:42.58343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:42.281817Z","time spent":"301.487492ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:44.27849Z","caller":"traceutil/trace.go:171","msg":"trace[732141869] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"207.295684ms","start":"2026-02-25T01:41:44.071168Z","end":"2026-02-25T01:41:44.278464Z","steps":["trace[732141869] 'process raft request' (duration: 201.413972ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:44.609766Z","caller":"traceutil/trace.go:171","msg":"trace[1621008346] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"247.079829ms","start":"2026-02-25T01:41:44.362642Z","end":"2026-02-25T01:41:44.609722Z","steps":["trace[1621008346] 'process raft request' (duration: 246.828793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:45.543831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.643725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:41:45.544Z","caller":"traceutil/trace.go:171","msg":"trace[1113084632] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"928.56582ms","start":"2026-02-25T01:41:44.615413Z","end":"2026-02-25T01:41:45.543979Z","steps":["trace[1113084632] 'process raft request' (duration: 346.599377ms)","trace[1113084632] 'compare' (duration: 581.315227ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:45.544066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:44.61539Z","time spent":"928.648472ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:41:45.832928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.906299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:41:45.833081Z","caller":"traceutil/trace.go:171","msg":"trace[618339695] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"1.217185189s","start":"2026-02-25T01:41:44.615864Z","end":"2026-02-25T01:41:45.833049Z","steps":["trace[618339695] 'process raft request' (duration: 1.037039964s)","trace[618339695] 'compare' (duration: 179.517409ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:45.833733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:44.615845Z","time spent":"1.217273052s","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:41:46.021593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.809802ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T01:41:46.021758Z","caller":"traceutil/trace.go:171","msg":"trace[1768363755] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:824; }","duration":"558.421341ms","start":"2026-02-25T01:41:45.463306Z","end":"2026-02-25T01:41:46.021728Z","steps":["trace[1768363755] 'read index received' (duration: 189.44698ms)","trace[1768363755] 'applied index is now lower than readState.Index' (duration: 368.971851ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:46.022133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.80716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:41:46.022182Z","caller":"traceutil/trace.go:171","msg":"trace[13100713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"558.913612ms","start":"2026-02-25T01:41:45.463255Z","end":"2026-02-25T01:41:46.022169Z","steps":["trace[13100713] 'agreement among raft nodes before linearized reading' (duration: 558.543154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:46.022226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:45.463229Z","time spent":"558.988215ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T01:41:52.379578Z","caller":"traceutil/trace.go:171","msg":"trace[1196957257] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"241.68737ms","start":"2026-02-25T01:41:52.137853Z","end":"2026-02-25T01:41:52.37954Z","steps":["trace[1196957257] 'process raft request' (duration: 241.522835ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:58.692833Z","caller":"traceutil/trace.go:171","msg":"trace[2115249315] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:915; }","duration":"230.477676ms","start":"2026-02-25T01:41:58.462336Z","end":"2026-02-25T01:41:58.692814Z","steps":["trace[2115249315] 'read index received' (duration: 230.318533ms)","trace[2115249315] 'applied index is now lower than readState.Index' (duration: 158.563µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:58.692899Z","caller":"traceutil/trace.go:171","msg":"trace[741620312] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"289.90198ms","start":"2026-02-25T01:41:58.402981Z","end":"2026-02-25T01:41:58.692883Z","steps":["trace[741620312] 'process raft request' (duration: 289.736826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:58.692983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.241162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T01:41:58.693006Z","caller":"traceutil/trace.go:171","msg":"trace[1429900654] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:880; }","duration":"214.294324ms","start":"2026-02-25T01:41:58.478701Z","end":"2026-02-25T01:41:58.692995Z","steps":["trace[1429900654] 'agreement among raft nodes before linearized reading' (duration: 214.200832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:58.693021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.665122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:41:58.693134Z","caller":"traceutil/trace.go:171","msg":"trace[1167294852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:880; }","duration":"230.821995ms","start":"2026-02-25T01:41:58.462286Z","end":"2026-02-25T01:41:58.693108Z","steps":["trace[1167294852] 'agreement among raft nodes before linearized reading' (duration: 230.65385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:00.258718Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256877331586719668,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:42:00.759794Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256877331586719668,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:42:00.840882Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.193799301s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T01:42:00.841255Z","caller":"traceutil/trace.go:171","msg":"trace[1532206385] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:918; }","duration":"1.083595465s","start":"2026-02-25T01:41:59.757637Z","end":"2026-02-25T01:42:00.841232Z","steps":["trace[1532206385] 'read index received' (duration: 1.083413111s)","trace[1532206385] 'applied index is now lower than readState.Index' (duration: 181.444µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:00.841543Z","caller":"traceutil/trace.go:171","msg":"trace[527209603] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"1.194522848s","start":"2026-02-25T01:41:59.647009Z","end":"2026-02-25T01:42:00.841532Z","steps":["trace[527209603] 'process raft request' (duration: 1.194102588s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:00.841641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:59.64699Z","time spent":"1.19458615s","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:00.841754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.746042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T01:42:00.841812Z","caller":"traceutil/trace.go:171","msg":"trace[1596984451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:883; }","duration":"848.846474ms","start":"2026-02-25T01:41:59.992952Z","end":"2026-02-25T01:42:00.841799Z","steps":["trace[1596984451] 'agreement among raft nodes before linearized reading' (duration: 848.645389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:00.841842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:59.992937Z","time spent":"848.899256ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T01:42:00.841868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.577431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:42:00.841944Z","caller":"traceutil/trace.go:171","msg":"trace[893555536] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:883; }","duration":"144.706774ms","start":"2026-02-25T01:42:00.697219Z","end":"2026-02-25T01:42:00.841926Z","steps":["trace[893555536] 'agreement among raft nodes before linearized reading' (duration: 144.455648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:00.842058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.196764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:00.842079Z","caller":"traceutil/trace.go:171","msg":"trace[1798459030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"378.247255ms","start":"2026-02-25T01:42:00.463825Z","end":"2026-02-25T01:42:00.842072Z","steps":["trace[1798459030] 'agreement among raft nodes before linearized reading' (duration: 378.215895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:00.842097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:00.463808Z","time spent":"378.285477ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:42:00.842116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084455636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:42:00.842161Z","caller":"traceutil/trace.go:171","msg":"trace[2058999858] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:883; }","duration":"1.084551908s","start":"2026-02-25T01:41:59.757596Z","end":"2026-02-25T01:42:00.842148Z","steps":["trace[2058999858] 'agreement among raft nodes before linearized reading' (duration: 1.084463476s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:00.842192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:59.757578Z","time spent":"1.084605549s","remote":"127.0.0.1:42022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:42:00.842213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.688731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T01:42:00.842232Z","caller":"traceutil/trace.go:171","msg":"trace[1712071473] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:883; }","duration":"680.729761ms","start":"2026-02-25T01:42:00.161497Z","end":"2026-02-25T01:42:00.842226Z","steps":["trace[1712071473] 'agreement among raft nodes before linearized reading' (duration: 680.67634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:00.84225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:00.161483Z","time spent":"680.760662ms","remote":"127.0.0.1:41960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.132\" "} {"level":"warn","ts":"2026-02-25T01:42:00.842275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.975783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T01:42:00.84231Z","caller":"traceutil/trace.go:171","msg":"trace[1582876474] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:883; }","duration":"141.041145ms","start":"2026-02-25T01:42:00.70126Z","end":"2026-02-25T01:42:00.842301Z","steps":["trace[1582876474] 'agreement among raft nodes before linearized reading' (duration: 140.940322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:00.842357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.166247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:42:00.842376Z","caller":"traceutil/trace.go:171","msg":"trace[12466967] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:883; }","duration":"841.213909ms","start":"2026-02-25T01:42:00.001156Z","end":"2026-02-25T01:42:00.84237Z","steps":["trace[12466967] 'agreement among raft nodes before linearized reading' (duration: 841.177728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:00.842393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:00.00114Z","time spent":"841.248489ms","remote":"127.0.0.1:42016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:42:01.344547Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256877331586719671,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:42:01.4848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:00.843383Z","time spent":"641.411886ms","remote":"127.0.0.1:41960","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T01:42:01.656897Z","caller":"traceutil/trace.go:171","msg":"trace[902090973] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"810.075032ms","start":"2026-02-25T01:42:00.846792Z","end":"2026-02-25T01:42:01.656867Z","steps":["trace[902090973] 'process raft request' (duration: 785.149477ms)","trace[902090973] 'compare' (duration: 24.647209ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:01.656965Z","caller":"traceutil/trace.go:171","msg":"trace[1584500089] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"810.083083ms","start":"2026-02-25T01:42:00.846847Z","end":"2026-02-25T01:42:01.656931Z","steps":["trace[1584500089] 'process raft request' (duration: 809.901549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:01.657028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:00.846776Z","time spent":"810.186585ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:01.657102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.953762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:01.657126Z","caller":"traceutil/trace.go:171","msg":"trace[1114719195] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"809.391736ms","start":"2026-02-25T01:42:00.847728Z","end":"2026-02-25T01:42:01.657119Z","steps":["trace[1114719195] 'process raft request' (duration: 809.095439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:01.657162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:00.84772Z","time spent":"809.425187ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:01.657159Z","caller":"traceutil/trace.go:171","msg":"trace[658559049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:887; }","duration":"813.047094ms","start":"2026-02-25T01:42:00.844096Z","end":"2026-02-25T01:42:01.657143Z","steps":["trace[658559049] 'agreement among raft nodes before linearized reading' (duration: 812.913571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:01.657207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:00.844078Z","time spent":"813.116276ms","remote":"127.0.0.1:41930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T01:42:01.657212Z","caller":"traceutil/trace.go:171","msg":"trace[1350858497] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"809.350015ms","start":"2026-02-25T01:42:00.847825Z","end":"2026-02-25T01:42:01.657175Z","steps":["trace[1350858497] 'process raft request' (duration: 809.048768ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:01.656954Z","caller":"traceutil/trace.go:171","msg":"trace[117750738] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:919; }","duration":"812.803188ms","start":"2026-02-25T01:42:00.844124Z","end":"2026-02-25T01:42:01.656928Z","steps":["trace[117750738] 'read index received' (duration: 637.108872ms)","trace[117750738] 'applied index is now lower than readState.Index' (duration: 175.692966ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:01.657329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:00.847819Z","time spent":"809.436587ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:01.657693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:00.846843Z","time spent":"810.79251ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:01.666264Z","caller":"traceutil/trace.go:171","msg":"trace[913827449] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"179.49986ms","start":"2026-02-25T01:42:01.486752Z","end":"2026-02-25T01:42:01.666251Z","steps":["trace[913827449] 'process raft request' (duration: 179.366047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:01.666369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.307862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:42:01.666486Z","caller":"traceutil/trace.go:171","msg":"trace[413639753] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:888; }","duration":"281.476286ms","start":"2026-02-25T01:42:01.384991Z","end":"2026-02-25T01:42:01.666467Z","steps":["trace[413639753] 'agreement among raft nodes before linearized reading' (duration: 281.266461ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:15.952323Z","caller":"traceutil/trace.go:171","msg":"trace[1590841828] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"106.31283ms","start":"2026-02-25T01:42:15.845988Z","end":"2026-02-25T01:42:15.952301Z","steps":["trace[1590841828] 'read index received' (duration: 106.127186ms)","trace[1590841828] 'applied index is now lower than readState.Index' (duration: 184.624µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:15.952451Z","caller":"traceutil/trace.go:171","msg":"trace[615335091] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"198.857834ms","start":"2026-02-25T01:42:15.753579Z","end":"2026-02-25T01:42:15.952436Z","steps":["trace[615335091] 'process raft request' (duration: 198.585378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:15.952705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.360092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T01:42:15.952778Z","caller":"traceutil/trace.go:171","msg":"trace[442098647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:925; }","duration":"101.469435ms","start":"2026-02-25T01:42:15.851292Z","end":"2026-02-25T01:42:15.952761Z","steps":["trace[442098647] 'agreement among raft nodes before linearized reading' (duration: 101.286991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:15.952996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.041327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T01:42:15.953041Z","caller":"traceutil/trace.go:171","msg":"trace[1955381260] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:925; }","duration":"107.110849ms","start":"2026-02-25T01:42:15.845918Z","end":"2026-02-25T01:42:15.953029Z","steps":["trace[1955381260] 'agreement among raft nodes before linearized reading' (duration: 107.008937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:15.953115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.425973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T01:42:15.953171Z","caller":"traceutil/trace.go:171","msg":"trace[1279749404] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:925; }","duration":"101.559498ms","start":"2026-02-25T01:42:15.8516Z","end":"2026-02-25T01:42:15.95316Z","steps":["trace[1279749404] 'agreement among raft nodes before linearized reading' (duration: 101.367423ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:16.08327Z","caller":"traceutil/trace.go:171","msg":"trace[616092923] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"123.202911ms","start":"2026-02-25T01:42:15.960047Z","end":"2026-02-25T01:42:16.08325Z","steps":["trace[616092923] 'process raft request' (duration: 122.72155ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:16.083455Z","caller":"traceutil/trace.go:171","msg":"trace[1704657786] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"123.317043ms","start":"2026-02-25T01:42:15.960127Z","end":"2026-02-25T01:42:16.083444Z","steps":["trace[1704657786] 'process raft request' (duration: 122.7326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:24.013616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.550562329s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:24.013798Z","caller":"traceutil/trace.go:171","msg":"trace[751081756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:971; }","duration":"1.550786053s","start":"2026-02-25T01:42:22.462992Z","end":"2026-02-25T01:42:24.013778Z","steps":["trace[751081756] 'range keys from in-memory index tree' (duration: 1.550482527s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:24.013848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:22.462974Z","time spent":"1.550863016s","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:42:24.013872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.334748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:42:24.01395Z","caller":"traceutil/trace.go:171","msg":"trace[156933180] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"932.032405ms","start":"2026-02-25T01:42:23.081905Z","end":"2026-02-25T01:42:24.013937Z","steps":["trace[156933180] 'read index received' (duration: 43.301µs)","trace[156933180] 'applied index is now lower than readState.Index' (duration: 931.988104ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:24.014178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.495867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T01:42:24.014192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.281441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T01:42:24.014217Z","caller":"traceutil/trace.go:171","msg":"trace[1533725661] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:972; }","duration":"711.572739ms","start":"2026-02-25T01:42:23.302635Z","end":"2026-02-25T01:42:24.014208Z","steps":["trace[1533725661] 'agreement among raft nodes before linearized reading' (duration: 711.504088ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:24.014243Z","caller":"traceutil/trace.go:171","msg":"trace[1683951158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:972; }","duration":"932.392093ms","start":"2026-02-25T01:42:23.081836Z","end":"2026-02-25T01:42:24.014228Z","steps":["trace[1683951158] 'agreement among raft nodes before linearized reading' (duration: 932.234839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:24.014289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:23.081817Z","time spent":"932.463375ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T01:42:24.014295Z","caller":"traceutil/trace.go:171","msg":"trace[321556260] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"1.239308118s","start":"2026-02-25T01:42:22.774976Z","end":"2026-02-25T01:42:24.014284Z","steps":["trace[321556260] 'process raft request' (duration: 272.494369ms)","trace[321556260] 'compare' (duration: 966.236446ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:24.014349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:22.774954Z","time spent":"1.23936612s","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:24.014248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:23.302617Z","time spent":"711.622271ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:42:24.50749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.999494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:42:24.507566Z","caller":"traceutil/trace.go:171","msg":"trace[494947104] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"468.859284ms","start":"2026-02-25T01:42:24.038696Z","end":"2026-02-25T01:42:24.507555Z","steps":["trace[494947104] 'read index received' (duration: 351.672116ms)","trace[494947104] 'applied index is now lower than readState.Index' (duration: 117.186398ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:24.5077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.990407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:24.507777Z","caller":"traceutil/trace.go:171","msg":"trace[2107944687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"469.10358ms","start":"2026-02-25T01:42:24.038657Z","end":"2026-02-25T01:42:24.50776Z","steps":["trace[2107944687] 'agreement among raft nodes before linearized reading' (duration: 468.978547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:24.507833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:24.038646Z","time spent":"469.180091ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:42:24.507879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.742713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:42:24.507907Z","caller":"traceutil/trace.go:171","msg":"trace[1094967987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:973; }","duration":"458.792485ms","start":"2026-02-25T01:42:24.049107Z","end":"2026-02-25T01:42:24.5079Z","steps":["trace[1094967987] 'agreement among raft nodes before linearized reading' (duration: 458.699173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:24.507953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:24.049096Z","time spent":"458.851236ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T01:42:24.508108Z","caller":"traceutil/trace.go:171","msg":"trace[552697190] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"474.295813ms","start":"2026-02-25T01:42:24.0338Z","end":"2026-02-25T01:42:24.508096Z","steps":["trace[552697190] 'process raft request' (duration: 356.628473ms)","trace[552697190] 'compare' (duration: 116.894282ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:24.508167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.470031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T01:42:24.508191Z","caller":"traceutil/trace.go:171","msg":"trace[477841103] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:973; }","duration":"351.514363ms","start":"2026-02-25T01:42:24.156671Z","end":"2026-02-25T01:42:24.508185Z","steps":["trace[477841103] 'agreement among raft nodes before linearized reading' (duration: 351.464091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:24.508212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:24.156658Z","time spent":"351.550273ms","remote":"127.0.0.1:42216","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-02-25T01:42:24.508278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:24.033773Z","time spent":"474.385315ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:24.508123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.403253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T01:42:24.508566Z","caller":"traceutil/trace.go:171","msg":"trace[1899740443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:973; }","duration":"322.861114ms","start":"2026-02-25T01:42:24.185695Z","end":"2026-02-25T01:42:24.508556Z","steps":["trace[1899740443] 'agreement among raft nodes before linearized reading' (duration: 322.376362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:24.508317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.874446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T01:42:24.50862Z","caller":"traceutil/trace.go:171","msg":"trace[1754891831] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:973; }","duration":"383.198284ms","start":"2026-02-25T01:42:24.12541Z","end":"2026-02-25T01:42:24.508608Z","steps":["trace[1754891831] 'agreement among raft nodes before linearized reading' (duration: 382.872186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:24.508626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:24.185683Z","time spent":"322.918396ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T01:42:24.508656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:24.125398Z","time spent":"383.246885ms","remote":"127.0.0.1:42216","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-02-25T01:42:24.508367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.405655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:42:24.508781Z","caller":"traceutil/trace.go:171","msg":"trace[932382699] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:973; }","duration":"447.821535ms","start":"2026-02-25T01:42:24.060945Z","end":"2026-02-25T01:42:24.508766Z","steps":["trace[932382699] 'agreement among raft nodes before linearized reading' (duration: 447.377954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:24.508816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:24.060904Z","time spent":"447.901777ms","remote":"127.0.0.1:42482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-02-25T01:42:24.734278Z","caller":"traceutil/trace.go:171","msg":"trace[515720824] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"220.407714ms","start":"2026-02-25T01:42:24.513848Z","end":"2026-02-25T01:42:24.734256Z","steps":["trace[515720824] 'process raft request' (duration: 220.360663ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:24.734555Z","caller":"traceutil/trace.go:171","msg":"trace[1646895110] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"222.003842ms","start":"2026-02-25T01:42:24.512532Z","end":"2026-02-25T01:42:24.734536Z","steps":["trace[1646895110] 'process raft request' (duration: 214.32803ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:24.73465Z","caller":"traceutil/trace.go:171","msg":"trace[1782772311] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"222.044883ms","start":"2026-02-25T01:42:24.512592Z","end":"2026-02-25T01:42:24.734637Z","steps":["trace[1782772311] 'process raft request' (duration: 221.533731ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:26.006069Z","caller":"traceutil/trace.go:171","msg":"trace[944824132] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"210.382177ms","start":"2026-02-25T01:42:25.795657Z","end":"2026-02-25T01:42:26.006039Z","steps":["trace[944824132] 'process raft request' (duration: 210.200573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:26.822668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.784838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T01:42:26.822782Z","caller":"traceutil/trace.go:171","msg":"trace[179593281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:979; }","duration":"275.950312ms","start":"2026-02-25T01:42:26.546812Z","end":"2026-02-25T01:42:26.822762Z","steps":["trace[179593281] 'range keys from in-memory index tree' (duration: 275.590383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:26.822972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.857715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T01:42:26.823054Z","caller":"traceutil/trace.go:171","msg":"trace[1133638557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:979; }","duration":"309.979658ms","start":"2026-02-25T01:42:26.513054Z","end":"2026-02-25T01:42:26.823034Z","steps":["trace[1133638557] 'range keys from in-memory index tree' (duration: 309.702821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:26.823107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:26.513041Z","time spent":"310.043459ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T01:42:27.163965Z","caller":"traceutil/trace.go:171","msg":"trace[1970972613] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"334.775025ms","start":"2026-02-25T01:42:26.82913Z","end":"2026-02-25T01:42:27.163905Z","steps":["trace[1970972613] 'process raft request' (duration: 334.638282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:27.164145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:26.829113Z","time spent":"334.946499ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:28.032291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.718681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:42:28.032626Z","caller":"traceutil/trace.go:171","msg":"trace[237765753] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1022; }","duration":"1.122697473s","start":"2026-02-25T01:42:26.909905Z","end":"2026-02-25T01:42:28.032603Z","steps":["trace[237765753] 'read index received' (duration: 254.227626ms)","trace[237765753] 'applied index is now lower than readState.Index' (duration: 868.468527ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:28.032818Z","caller":"traceutil/trace.go:171","msg":"trace[1364787301] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"1.202081906s","start":"2026-02-25T01:42:26.830701Z","end":"2026-02-25T01:42:28.032783Z","steps":["trace[1364787301] 'process raft request' (duration: 1.20184994s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:28.032863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.189793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:28.032856Z","caller":"traceutil/trace.go:171","msg":"trace[1312019790] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"1.202900405s","start":"2026-02-25T01:42:26.829919Z","end":"2026-02-25T01:42:28.032819Z","steps":["trace[1312019790] 'process raft request' (duration: 1.202492865s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:28.032934Z","caller":"traceutil/trace.go:171","msg":"trace[339861164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:984; }","duration":"569.337827ms","start":"2026-02-25T01:42:27.463581Z","end":"2026-02-25T01:42:28.032919Z","steps":["trace[339861164] 'agreement among raft nodes before linearized reading' (duration: 569.204884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:28.032968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:27.463559Z","time spent":"569.402448ms","remote":"127.0.0.1:41930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:42:28.032992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:26.830692Z","time spent":"1.202203389s","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:28.033023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:26.82991Z","time spent":"1.203041228s","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:28.033015Z","caller":"traceutil/trace.go:171","msg":"trace[470435577] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"1.203253863s","start":"2026-02-25T01:42:26.82973Z","end":"2026-02-25T01:42:28.032984Z","steps":["trace[470435577] 'process raft request' (duration: 881.746152ms)","trace[470435577] 'compare' (duration: 320.246791ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:28.033079Z","caller":"traceutil/trace.go:171","msg":"trace[454189831] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"1.202920166s","start":"2026-02-25T01:42:26.830143Z","end":"2026-02-25T01:42:28.033063Z","steps":["trace[454189831] 'process raft request' (duration: 1.202357472s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:28.033144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123235887s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:42:28.033148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:26.829722Z","time spent":"1.203348375s","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:28.03316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:26.830136Z","time spent":"1.202981537s","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:28.033176Z","caller":"traceutil/trace.go:171","msg":"trace[1479866325] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:984; }","duration":"1.123289488s","start":"2026-02-25T01:42:26.909875Z","end":"2026-02-25T01:42:28.033164Z","steps":["trace[1479866325] 'agreement among raft nodes before linearized reading' (duration: 1.123241227s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:28.033208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:26.909862Z","time spent":"1.123340679s","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:42:28.82837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.089605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:28.828764Z","caller":"traceutil/trace.go:171","msg":"trace[287258339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:984; }","duration":"349.549706ms","start":"2026-02-25T01:42:28.479198Z","end":"2026-02-25T01:42:28.828748Z","steps":["trace[287258339] 'range keys from in-memory index tree' (duration: 349.018784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:28.828796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:28.479181Z","time spent":"349.605727ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T01:42:29.343141Z","caller":"traceutil/trace.go:171","msg":"trace[1112930422] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"170.797279ms","start":"2026-02-25T01:42:29.17232Z","end":"2026-02-25T01:42:29.343118Z","steps":["trace[1112930422] 'process raft request' (duration: 170.675146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:29.582124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.967027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:29.582223Z","caller":"traceutil/trace.go:171","msg":"trace[1887316048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:985; }","duration":"118.124751ms","start":"2026-02-25T01:42:29.464073Z","end":"2026-02-25T01:42:29.582198Z","steps":["trace[1887316048] 'range keys from in-memory index tree' (duration: 117.859223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:30.66332Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256877331586719917,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T01:42:30.980324Z","caller":"traceutil/trace.go:171","msg":"trace[760910290] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"937.541854ms","start":"2026-02-25T01:42:30.042751Z","end":"2026-02-25T01:42:30.980293Z","steps":["trace[760910290] 'process raft request' (duration: 857.021996ms)","trace[760910290] 'compare' (duration: 80.106558ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:30.980493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:30.042735Z","time spent":"937.666387ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:30.980581Z","caller":"traceutil/trace.go:171","msg":"trace[1339208090] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"937.616716ms","start":"2026-02-25T01:42:30.042937Z","end":"2026-02-25T01:42:30.980554Z","steps":["trace[1339208090] 'process raft request' (duration: 937.271318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:30.980794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:30.04292Z","time spent":"937.733958ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:30.982222Z","caller":"traceutil/trace.go:171","msg":"trace[494000189] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1028; }","duration":"819.574627ms","start":"2026-02-25T01:42:30.162601Z","end":"2026-02-25T01:42:30.982175Z","steps":["trace[494000189] 'read index received' (duration: 737.186255ms)","trace[494000189] 'applied index is now lower than readState.Index' (duration: 82.386232ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:30.98227Z","caller":"traceutil/trace.go:171","msg":"trace[2146218001] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"938.834454ms","start":"2026-02-25T01:42:30.043399Z","end":"2026-02-25T01:42:30.982233Z","steps":["trace[2146218001] 'process raft request' (duration: 937.081923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:30.982533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.325172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:42:30.98256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.036881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T01:42:30.982532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.921186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T01:42:30.982625Z","caller":"traceutil/trace.go:171","msg":"trace[1386633319] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:989; }","duration":"820.049069ms","start":"2026-02-25T01:42:30.162562Z","end":"2026-02-25T01:42:30.982611Z","steps":["trace[1386633319] 'agreement among raft nodes before linearized reading' (duration: 819.833233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:30.982713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:30.162547Z","time spent":"820.154771ms","remote":"127.0.0.1:41960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.132\" "} {"level":"info","ts":"2026-02-25T01:42:30.982623Z","caller":"traceutil/trace.go:171","msg":"trace[2006816708] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:989; }","duration":"371.469946ms","start":"2026-02-25T01:42:30.611132Z","end":"2026-02-25T01:42:30.982602Z","steps":["trace[2006816708] 'agreement among raft nodes before linearized reading' (duration: 371.356943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:30.982885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:30.611099Z","time spent":"371.768112ms","remote":"127.0.0.1:42446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:42:30.982861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.804842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:30.982956Z","caller":"traceutil/trace.go:171","msg":"trace[1735611366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:989; }","duration":"519.964926ms","start":"2026-02-25T01:42:30.462972Z","end":"2026-02-25T01:42:30.982937Z","steps":["trace[1735611366] 'agreement among raft nodes before linearized reading' (duration: 519.650558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:30.983007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:30.462943Z","time spent":"520.054878ms","remote":"127.0.0.1:41930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T01:42:30.983072Z","caller":"traceutil/trace.go:171","msg":"trace[2008979049] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:989; }","duration":"715.141643ms","start":"2026-02-25T01:42:30.267461Z","end":"2026-02-25T01:42:30.982602Z","steps":["trace[2008979049] 'agreement among raft nodes before linearized reading' (duration: 714.964938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:30.982782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:30.043383Z","time spent":"939.096031ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:30.983155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:30.267436Z","time spent":"715.686575ms","remote":"127.0.0.1:42362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:42:31.113074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.549534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-25T01:42:31.113244Z","caller":"traceutil/trace.go:171","msg":"trace[974054320] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:989; }","duration":"100.79522ms","start":"2026-02-25T01:42:31.012424Z","end":"2026-02-25T01:42:31.113219Z","steps":["trace[974054320] 'agreement among raft nodes before linearized reading' (duration: 67.633294ms)","trace[974054320] 'range keys from in-memory index tree' (duration: 32.840819ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:31.547988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.582207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:42:31.548106Z","caller":"traceutil/trace.go:171","msg":"trace[460526699] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1033; }","duration":"429.530881ms","start":"2026-02-25T01:42:31.118557Z","end":"2026-02-25T01:42:31.548088Z","steps":["trace[460526699] 'read index received' (duration: 292.818651ms)","trace[460526699] 'applied index is now lower than readState.Index' (duration: 136.71135ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:31.548238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.677944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-25T01:42:31.54822Z","caller":"traceutil/trace.go:171","msg":"trace[341596912] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"429.832158ms","start":"2026-02-25T01:42:31.118355Z","end":"2026-02-25T01:42:31.548187Z","steps":["trace[341596912] 'process raft request' (duration: 292.937174ms)","trace[341596912] 'compare' (duration: 136.448314ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:31.548266Z","caller":"traceutil/trace.go:171","msg":"trace[1885859738] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:991; }","duration":"429.734426ms","start":"2026-02-25T01:42:31.118523Z","end":"2026-02-25T01:42:31.548257Z","steps":["trace[1885859738] 'agreement among raft nodes before linearized reading' (duration: 429.661184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:31.548302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:31.118516Z","time spent":"429.777967ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-25T01:42:31.548365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:31.118331Z","time spent":"429.958532ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:31.548643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.583339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T01:42:31.548673Z","caller":"traceutil/trace.go:171","msg":"trace[1993037640] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:991; }","duration":"201.66985ms","start":"2026-02-25T01:42:31.346994Z","end":"2026-02-25T01:42:31.548664Z","steps":["trace[1993037640] 'agreement among raft nodes before linearized reading' (duration: 201.541887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:32.194475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.186733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:42:32.194613Z","caller":"traceutil/trace.go:171","msg":"trace[380740054] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1034; }","duration":"625.111159ms","start":"2026-02-25T01:42:31.569477Z","end":"2026-02-25T01:42:32.194588Z","steps":["trace[380740054] 'read index received' (duration: 2.911729ms)","trace[380740054] 'applied index is now lower than readState.Index' (duration: 622.19774ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:32.194895Z","caller":"traceutil/trace.go:171","msg":"trace[1282101819] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"625.469147ms","start":"2026-02-25T01:42:31.569405Z","end":"2026-02-25T01:42:32.194875Z","steps":["trace[1282101819] 'process raft request' (duration: 206.750502ms)","trace[1282101819] 'compare' (duration: 417.782434ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:32.194987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:31.569293Z","time spent":"625.642651ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:32.195277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.791715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-25T01:42:32.195324Z","caller":"traceutil/trace.go:171","msg":"trace[261514661] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:993; }","duration":"625.839136ms","start":"2026-02-25T01:42:31.56947Z","end":"2026-02-25T01:42:32.195309Z","steps":["trace[261514661] 'agreement among raft nodes before linearized reading' (duration: 625.722533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:32.195361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:31.569409Z","time spent":"625.942969ms","remote":"127.0.0.1:42224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-02-25T01:42:33.332483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.967318ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:42:33.332589Z","caller":"traceutil/trace.go:171","msg":"trace[1874916585] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1036; }","duration":"869.128831ms","start":"2026-02-25T01:42:32.463444Z","end":"2026-02-25T01:42:33.332573Z","steps":["trace[1874916585] 'read index received' (duration: 274.908117ms)","trace[1874916585] 'applied index is now lower than readState.Index' (duration: 594.219494ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:33.332929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.032813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T01:42:33.332971Z","caller":"traceutil/trace.go:171","msg":"trace[2140635862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:994; }","duration":"344.110135ms","start":"2026-02-25T01:42:32.98885Z","end":"2026-02-25T01:42:33.33296Z","steps":["trace[2140635862] 'agreement among raft nodes before linearized reading' (duration: 343.981392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:33.33301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:32.98883Z","time spent":"344.171747ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T01:42:33.333093Z","caller":"traceutil/trace.go:171","msg":"trace[1440992138] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"1.133846907s","start":"2026-02-25T01:42:32.199165Z","end":"2026-02-25T01:42:33.333012Z","steps":["trace[1440992138] 'process raft request' (duration: 539.276283ms)","trace[1440992138] 'compare' (duration: 593.852186ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:33.333279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:32.199145Z","time spent":"1.134070602s","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:33.333349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.444008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-25T01:42:33.333368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.427029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:33.33339Z","caller":"traceutil/trace.go:171","msg":"trace[2051992237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:994; }","duration":"869.997852ms","start":"2026-02-25T01:42:32.463384Z","end":"2026-02-25T01:42:33.333382Z","steps":["trace[2051992237] 'agreement among raft nodes before linearized reading' (duration: 869.291535ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:33.333387Z","caller":"traceutil/trace.go:171","msg":"trace[737461226] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:994; }","duration":"345.509249ms","start":"2026-02-25T01:42:32.987867Z","end":"2026-02-25T01:42:33.333377Z","steps":["trace[737461226] 'agreement among raft nodes before linearized reading' (duration: 345.356486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:33.33341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:32.463357Z","time spent":"870.047594ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:42:33.333414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:32.987856Z","time spent":"345.55098ms","remote":"127.0.0.1:42216","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-02-25T01:42:33.33343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.06048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T01:42:33.333494Z","caller":"traceutil/trace.go:171","msg":"trace[1942818403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:994; }","duration":"348.182132ms","start":"2026-02-25T01:42:32.985297Z","end":"2026-02-25T01:42:33.333479Z","steps":["trace[1942818403] 'agreement among raft nodes before linearized reading' (duration: 347.412234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:33.33355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:32.985275Z","time spent":"348.269225ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T01:42:33.808208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.327889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:42:33.808552Z","caller":"traceutil/trace.go:171","msg":"trace[851330950] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"472.150763ms","start":"2026-02-25T01:42:33.33635Z","end":"2026-02-25T01:42:33.8085Z","steps":["trace[851330950] 'process raft request' (duration: 182.406305ms)","trace[851330950] 'compare' (duration: 289.019271ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:33.808773Z","caller":"traceutil/trace.go:171","msg":"trace[1690601958] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1037; }","duration":"405.086913ms","start":"2026-02-25T01:42:33.403657Z","end":"2026-02-25T01:42:33.808744Z","steps":["trace[1690601958] 'read index received' (duration: 115.111979ms)","trace[1690601958] 'applied index is now lower than readState.Index' (duration: 289.974134ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:33.808848Z","caller":"traceutil/trace.go:171","msg":"trace[972546266] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"472.373548ms","start":"2026-02-25T01:42:33.336448Z","end":"2026-02-25T01:42:33.808822Z","steps":["trace[972546266] 'process raft request' (duration: 471.922827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:33.808931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.226166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:33.808908Z","caller":"traceutil/trace.go:171","msg":"trace[931949198] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"471.17844ms","start":"2026-02-25T01:42:33.3377Z","end":"2026-02-25T01:42:33.808878Z","steps":["trace[931949198] 'process raft request' (duration: 470.913854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:33.809005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:33.336438Z","time spent":"472.499411ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:33.809108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:33.337692Z","time spent":"471.322263ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:33.808976Z","caller":"traceutil/trace.go:171","msg":"trace[2088149336] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:997; }","duration":"405.357629ms","start":"2026-02-25T01:42:33.403606Z","end":"2026-02-25T01:42:33.808964Z","steps":["trace[2088149336] 'agreement among raft nodes before linearized reading' (duration: 405.246986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:33.809437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:33.403585Z","time spent":"405.826591ms","remote":"127.0.0.1:42462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:42:33.809503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:33.336333Z","time spent":"472.498631ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:37.03392Z","caller":"traceutil/trace.go:171","msg":"trace[692174707] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"443.684848ms","start":"2026-02-25T01:42:36.590202Z","end":"2026-02-25T01:42:37.033887Z","steps":["trace[692174707] 'process raft request' (duration: 443.478533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:37.034266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:36.590186Z","time spent":"443.829441ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:37.992321Z","caller":"traceutil/trace.go:171","msg":"trace[2027008438] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"104.480378ms","start":"2026-02-25T01:42:37.887808Z","end":"2026-02-25T01:42:37.992288Z","steps":["trace[2027008438] 'process raft request' (duration: 52.143527ms)","trace[2027008438] 'compare' (duration: 52.211568ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:38.109827Z","caller":"traceutil/trace.go:171","msg":"trace[1916106373] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"112.325302ms","start":"2026-02-25T01:42:37.997479Z","end":"2026-02-25T01:42:38.109805Z","steps":["trace[1916106373] 'process raft request' (duration: 111.999304ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:39.538223Z","caller":"traceutil/trace.go:171","msg":"trace[1176243071] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"150.317774ms","start":"2026-02-25T01:42:39.387874Z","end":"2026-02-25T01:42:39.538192Z","steps":["trace[1176243071] 'process raft request' (duration: 150.14327ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:43.680716Z","caller":"traceutil/trace.go:171","msg":"trace[654925106] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"123.734783ms","start":"2026-02-25T01:42:43.556945Z","end":"2026-02-25T01:42:43.68068Z","steps":["trace[654925106] 'process raft request' (duration: 123.570859ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:01.956141Z","caller":"traceutil/trace.go:171","msg":"trace[15873746] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"123.974048ms","start":"2026-02-25T01:43:01.832132Z","end":"2026-02-25T01:43:01.956106Z","steps":["trace[15873746] 'process raft request' (duration: 123.775083ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:28.626405Z","caller":"traceutil/trace.go:171","msg":"trace[1696197655] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1296; }","duration":"162.994242ms","start":"2026-02-25T01:43:28.463386Z","end":"2026-02-25T01:43:28.62638Z","steps":["trace[1696197655] 'read index received' (duration: 8.154252ms)","trace[1696197655] 'applied index is now lower than readState.Index' (duration: 154.83915ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:43:28.626638Z","caller":"traceutil/trace.go:171","msg":"trace[773462684] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"211.121384ms","start":"2026-02-25T01:43:28.415507Z","end":"2026-02-25T01:43:28.626629Z","steps":["trace[773462684] 'process raft request' (duration: 135.705591ms)","trace[773462684] 'compare' (duration: 75.068394ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:43:28.626855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.417862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:43:28.626887Z","caller":"traceutil/trace.go:171","msg":"trace[35400542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1244; }","duration":"163.546865ms","start":"2026-02-25T01:43:28.463331Z","end":"2026-02-25T01:43:28.626878Z","steps":["trace[35400542] 'agreement among raft nodes before linearized reading' (duration: 163.441782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:28.627064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.560824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:43:28.627091Z","caller":"traceutil/trace.go:171","msg":"trace[338231436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1244; }","duration":"120.631646ms","start":"2026-02-25T01:43:28.506453Z","end":"2026-02-25T01:43:28.627084Z","steps":["trace[338231436] 'agreement among raft nodes before linearized reading' (duration: 120.538793ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:39.936574Z","caller":"traceutil/trace.go:171","msg":"trace[1336776433] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"113.955229ms","start":"2026-02-25T01:43:39.822599Z","end":"2026-02-25T01:43:39.936554Z","steps":["trace[1336776433] 'process raft request' (duration: 113.872888ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:40.973892Z","caller":"traceutil/trace.go:171","msg":"trace[923506376] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"107.445395ms","start":"2026-02-25T01:43:40.866423Z","end":"2026-02-25T01:43:40.973869Z","steps":["trace[923506376] 'process raft request' (duration: 107.333602ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:45:49.121051Z","caller":"traceutil/trace.go:171","msg":"trace[1818846519] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1927; }","duration":"196.395406ms","start":"2026-02-25T01:45:48.924632Z","end":"2026-02-25T01:45:49.121028Z","steps":["trace[1818846519] 'read index received' (duration: 196.051897ms)","trace[1818846519] 'applied index is now lower than readState.Index' (duration: 342.079µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:45:49.121316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.664972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-25T01:45:49.121357Z","caller":"traceutil/trace.go:171","msg":"trace[360638686] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1845; }","duration":"196.768384ms","start":"2026-02-25T01:45:48.924578Z","end":"2026-02-25T01:45:49.121346Z","steps":["trace[360638686] 'agreement among raft nodes before linearized reading' (duration: 196.553359ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:45:49.121315Z","caller":"traceutil/trace.go:171","msg":"trace[2097249585] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"577.029845ms","start":"2026-02-25T01:45:48.54426Z","end":"2026-02-25T01:45:49.12129Z","steps":["trace[2097249585] 'process raft request' (duration: 576.433341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:45:49.121868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:45:48.544238Z","time spent":"577.544867ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:45:49.36549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.655774ms","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-02-25T01:45:49.365598Z","caller":"traceutil/trace.go:171","msg":"trace[614776449] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1845; }","duration":"177.773567ms","start":"2026-02-25T01:45:49.187809Z","end":"2026-02-25T01:45:49.365582Z","steps":["trace[614776449] 'range keys from in-memory index tree' (duration: 177.610973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:45:49.365755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.971073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:45:49.365871Z","caller":"traceutil/trace.go:171","msg":"trace[957823465] linearizableReadLoop","detail":"{readStateIndex:1929; appliedIndex:1928; }","duration":"193.669632ms","start":"2026-02-25T01:45:49.172186Z","end":"2026-02-25T01:45:49.365855Z","steps":["trace[957823465] 'read index received' (duration: 31.471765ms)","trace[957823465] 'applied index is now lower than readState.Index' (duration: 162.196747ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:45:49.365884Z","caller":"traceutil/trace.go:171","msg":"trace[2070851376] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"239.922938ms","start":"2026-02-25T01:45:49.125944Z","end":"2026-02-25T01:45:49.365867Z","steps":["trace[2070851376] 'process raft request' (duration: 77.755151ms)","trace[2070851376] 'compare' (duration: 161.84436ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:45:49.366099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.908538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T01:45:49.366174Z","caller":"traceutil/trace.go:171","msg":"trace[1279656626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1846; }","duration":"194.032021ms","start":"2026-02-25T01:45:49.172125Z","end":"2026-02-25T01:45:49.366157Z","steps":["trace[1279656626] 'agreement among raft nodes before linearized reading' (duration: 193.814046ms)"],"step_count":1}