{"level":"warn","ts":"2026-03-06T22:00:13.061561Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-06T22:00:13.061697Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-06T22:00:13.062955Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-06T22:00:13.063Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-03-06T22:00:13.063037Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-06T22:00:13.063994Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-03-06T22:00:13.064104Z","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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-06T22:00:13.068379Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.964459ms"} {"level":"info","ts":"2026-03-06T22:00:13.077148Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-03-06T22:00:13.077242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-03-06T22:00:13.077272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-03-06T22:00:13.077283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-06T22:00:13.077292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-03-06T22:00:13.077431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-03-06T22:00:13.085259Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-06T22:00:13.08661Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-06T22:00:13.087635Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-06T22:00:13.089865Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-06T22:00:13.090221Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-06T22:00:13.089968Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-06T22:00:13.091363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-03-06T22:00:13.091454Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-06T22:00:13.091957Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-06T22:00:13.092169Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-03-06T22:00:13.094524Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-06T22:00:13.094715Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-03-06T22:00:13.094773Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-03-06T22:00:13.094998Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-06T22:00:13.095058Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-06T22:00:14.077883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-03-06T22:00:14.077947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-06T22:00:14.077992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-03-06T22:00:14.078012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-03-06T22:00:14.078024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-03-06T22:00:14.07804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-03-06T22:00:14.078234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-03-06T22:00:14.166323Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T22:00:14.167757Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-03-06T22:00:14.1678Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-06T22:00:14.168185Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-06T22:00:14.168239Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-06T22:00:14.167763Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-06T22:00:14.16845Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T22:00:14.16859Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T22:00:14.168638Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T22:00:14.171788Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-03-06T22:00:14.173504Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-06T22:00:21.191993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.649186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-06T22:00:21.192021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.440477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-06T22:00:21.192113Z","caller":"traceutil/trace.go:171","msg":"trace[1110314112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:326; }","duration":"242.79986ms","start":"2026-03-06T22:00:20.949293Z","end":"2026-03-06T22:00:21.192093Z","steps":["trace[1110314112] 'range keys from in-memory index tree' (duration: 242.579925ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:00:21.192124Z","caller":"traceutil/trace.go:171","msg":"trace[1412423369] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:326; }","duration":"742.602821ms","start":"2026-03-06T22:00:20.4495Z","end":"2026-03-06T22:00:21.192103Z","steps":["trace[1412423369] 'range keys from in-memory index tree' (duration: 742.330215ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:21.192177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:00:20.449481Z","time spent":"742.683154ms","remote":"127.0.0.1:54836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-06T22:00:21.192579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.328723ms","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-03-06T22:00:21.192652Z","caller":"traceutil/trace.go:171","msg":"trace[675874282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:326; }","duration":"243.429346ms","start":"2026-03-06T22:00:20.949211Z","end":"2026-03-06T22:00:21.192641Z","steps":["trace[675874282] 'range keys from in-memory index tree' (duration: 242.501523ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:00:21.923651Z","caller":"traceutil/trace.go:171","msg":"trace[299198883] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"728.117902ms","start":"2026-03-06T22:00:21.195511Z","end":"2026-03-06T22:00:21.923629Z","steps":["trace[299198883] 'process raft request' (duration: 727.970748ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:21.924559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:00:21.195484Z","time spent":"728.240694ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:00:23.816934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.731655ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-06T22:00:23.81715Z","caller":"traceutil/trace.go:171","msg":"trace[661039689] linearizableReadLoop","detail":"{readStateIndex:340; appliedIndex:337; }","duration":"2.141384308s","start":"2026-03-06T22:00:21.675745Z","end":"2026-03-06T22:00:23.81713Z","steps":["trace[661039689] 'read index received' (duration: 247.711312ms)","trace[661039689] 'applied index is now lower than readState.Index' (duration: 1.893671656s)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:00:23.817183Z","caller":"traceutil/trace.go:171","msg":"trace[648188813] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"2.620893007s","start":"2026-03-06T22:00:21.196259Z","end":"2026-03-06T22:00:23.817152Z","steps":["trace[648188813] 'process raft request' (duration: 2.620792104s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:23.817347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.141579463s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-06T22:00:23.817346Z","caller":"traceutil/trace.go:171","msg":"trace[303758927] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"2.621528082s","start":"2026-03-06T22:00:21.195789Z","end":"2026-03-06T22:00:23.817317Z","steps":["trace[303758927] 'process raft request' (duration: 1.758200032s)","trace[303758927] 'compare' (duration: 862.592151ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:00:23.817372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:00:21.196247Z","time spent":"2.6210314s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:00:23.817405Z","caller":"traceutil/trace.go:171","msg":"trace[58950844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:329; }","duration":"2.141682146s","start":"2026-03-06T22:00:21.675709Z","end":"2026-03-06T22:00:23.817391Z","steps":["trace[58950844] 'agreement among raft nodes before linearized reading' (duration: 2.141540712s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:23.817456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:00:21.675692Z","time spent":"2.141750997s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-06T22:00:23.817458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:00:21.195769Z","time spent":"2.621647815s","remote":"127.0.0.1:54920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T22:00:23.817534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.69488208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-06T22:00:23.817636Z","caller":"traceutil/trace.go:171","msg":"trace[1544434184] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:329; }","duration":"1.695027723s","start":"2026-03-06T22:00:22.122565Z","end":"2026-03-06T22:00:23.817593Z","steps":["trace[1544434184] 'agreement among raft nodes before linearized reading' (duration: 1.694814467s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:23.817685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:00:22.122543Z","time spent":"1.695130755s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-06T22:00:44.093391Z","caller":"traceutil/trace.go:171","msg":"trace[900453049] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"181.011646ms","start":"2026-03-06T22:00:43.912353Z","end":"2026-03-06T22:00:44.093365Z","steps":["trace[900453049] 'process raft request' (duration: 180.869503ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:00:44.094774Z","caller":"traceutil/trace.go:171","msg":"trace[2082804216] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:524; }","duration":"177.808365ms","start":"2026-03-06T22:00:43.916944Z","end":"2026-03-06T22:00:44.094753Z","steps":["trace[2082804216] 'read index received' (duration: 176.256977ms)","trace[2082804216] 'applied index is now lower than readState.Index' (duration: 1.550638ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:00:44.094858Z","caller":"traceutil/trace.go:171","msg":"trace[429145791] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"180.340909ms","start":"2026-03-06T22:00:43.914509Z","end":"2026-03-06T22:00:44.09485Z","steps":["trace[429145791] 'process raft request' (duration: 180.135294ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:44.094996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.041691ms","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-03-06T22:00:44.09503Z","caller":"traceutil/trace.go:171","msg":"trace[2097191665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:508; }","duration":"178.117213ms","start":"2026-03-06T22:00:43.9169Z","end":"2026-03-06T22:00:44.095017Z","steps":["trace[2097191665] 'agreement among raft nodes before linearized reading' (duration: 178.049461ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:45.557548Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051156221303138,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-06T22:00:45.675894Z","caller":"traceutil/trace.go:171","msg":"trace[604126299] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:528; }","duration":"619.104736ms","start":"2026-03-06T22:00:45.05676Z","end":"2026-03-06T22:00:45.675864Z","steps":["trace[604126299] 'read index received' (duration: 618.91324ms)","trace[604126299] 'applied index is now lower than readState.Index' (duration: 189.725µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:00:45.676027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.251569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:00:45.675988Z","caller":"traceutil/trace.go:171","msg":"trace[1984073206] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"947.539072ms","start":"2026-03-06T22:00:44.728418Z","end":"2026-03-06T22:00:45.675957Z","steps":["trace[1984073206] 'process raft request' (duration: 947.262404ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:00:45.676076Z","caller":"traceutil/trace.go:171","msg":"trace[1729026863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"619.352671ms","start":"2026-03-06T22:00:45.05671Z","end":"2026-03-06T22:00:45.676062Z","steps":["trace[1729026863] 'agreement among raft nodes before linearized reading' (duration: 619.260299ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:45.676121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:00:45.056687Z","time spent":"619.423814ms","remote":"127.0.0.1:52046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T22:00:45.676176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:00:44.728398Z","time spent":"947.681516ms","remote":"127.0.0.1:54888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:00:52.543369Z","caller":"traceutil/trace.go:171","msg":"trace[888903813] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"410.423403ms","start":"2026-03-06T22:00:52.132928Z","end":"2026-03-06T22:00:52.543352Z","steps":["trace[888903813] 'read index received' (duration: 410.231329ms)","trace[888903813] 'applied index is now lower than readState.Index' (duration: 191.474µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:00:52.543715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.163487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-06T22:00:52.54374Z","caller":"traceutil/trace.go:171","msg":"trace[1844912785] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"419.57232ms","start":"2026-03-06T22:00:52.12414Z","end":"2026-03-06T22:00:52.543712Z","steps":["trace[1844912785] 'process raft request' (duration: 419.081498ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:00:52.543763Z","caller":"traceutil/trace.go:171","msg":"trace[1565448974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:530; }","duration":"402.245899ms","start":"2026-03-06T22:00:52.141502Z","end":"2026-03-06T22:00:52.543748Z","steps":["trace[1565448974] 'agreement among raft nodes before linearized reading' (duration: 402.107526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:52.543856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.150446ms","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-03-06T22:00:52.543928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:00:52.124123Z","time spent":"419.678172ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:00:52.543875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.207727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-pmctb\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-06T22:00:52.543976Z","caller":"traceutil/trace.go:171","msg":"trace[688263559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:530; }","duration":"402.322701ms","start":"2026-03-06T22:00:52.141629Z","end":"2026-03-06T22:00:52.543952Z","steps":["trace[688263559] 'agreement among raft nodes before linearized reading' (duration: 402.087265ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:52.544035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:00:52.141623Z","time spent":"402.399883ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-06T22:00:52.544002Z","caller":"traceutil/trace.go:171","msg":"trace[1913241442] range","detail":"{range_begin:/registry/pods/kube-system/cilium-pmctb; range_end:; response_count:1; response_revision:530; }","duration":"381.398092ms","start":"2026-03-06T22:00:52.162593Z","end":"2026-03-06T22:00:52.543991Z","steps":["trace[1913241442] 'agreement among raft nodes before linearized reading' (duration: 381.095904ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:52.544152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:00:52.162573Z","time spent":"381.566266ms","remote":"127.0.0.1:54896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-pmctb\" "} {"level":"warn","ts":"2026-03-06T22:00:52.543902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:00:52.14149Z","time spent":"402.399982ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-06T22:00:52.544416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.494209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-06T22:00:52.544452Z","caller":"traceutil/trace.go:171","msg":"trace[302742405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:530; }","duration":"411.569611ms","start":"2026-03-06T22:00:52.132874Z","end":"2026-03-06T22:00:52.544443Z","steps":["trace[302742405] 'agreement among raft nodes before linearized reading' (duration: 410.785452ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:52.544515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:00:52.132852Z","time spent":"411.657613ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-06T22:00:52.728047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.858683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T22:00:52.728171Z","caller":"traceutil/trace.go:171","msg":"trace[34819085] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"179.657982ms","start":"2026-03-06T22:00:52.548489Z","end":"2026-03-06T22:00:52.728147Z","steps":["trace[34819085] 'process raft request' (duration: 66.604644ms)","trace[34819085] 'compare' (duration: 112.706339ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:00:53.22615Z","caller":"traceutil/trace.go:171","msg":"trace[674511423] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"677.088204ms","start":"2026-03-06T22:00:52.549037Z","end":"2026-03-06T22:00:53.226125Z","steps":["trace[674511423] 'process raft request' (duration: 621.35232ms)","trace[674511423] 'compare' (duration: 55.311844ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:00:53.226259Z","caller":"traceutil/trace.go:171","msg":"trace[1959379038] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"677.177177ms","start":"2026-03-06T22:00:52.54905Z","end":"2026-03-06T22:00:53.226227Z","steps":["trace[1959379038] 'process raft request' (duration: 676.922931ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:53.2264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:00:52.549029Z","time spent":"677.28869ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:00:53.226472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:00:52.549013Z","time spent":"677.253429ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:00:53.226639Z","caller":"traceutil/trace.go:171","msg":"trace[759367399] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"668.814189ms","start":"2026-03-06T22:00:52.557811Z","end":"2026-03-06T22:00:53.226626Z","steps":["trace[759367399] 'process raft request' (duration: 668.302087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:53.226752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:00:52.557795Z","time spent":"668.914322ms","remote":"127.0.0.1:54896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:00:54.771895Z","caller":"traceutil/trace.go:171","msg":"trace[1995356040] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"219.669135ms","start":"2026-03-06T22:00:54.552185Z","end":"2026-03-06T22:00:54.771854Z","steps":["trace[1995356040] 'process raft request' (duration: 219.364207ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:55.730907Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051156221303201,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T22:00:56.042389Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.266312608s","expected-duration":"1s"} {"level":"info","ts":"2026-03-06T22:00:56.042936Z","caller":"traceutil/trace.go:171","msg":"trace[1214708320] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.266915793s","start":"2026-03-06T22:00:54.77599Z","end":"2026-03-06T22:00:56.042905Z","steps":["trace[1214708320] 'process raft request' (duration: 1.266687947s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:56.043086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:00:54.775974Z","time spent":"1.267021906s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:00:56.043578Z","caller":"traceutil/trace.go:171","msg":"trace[2001287282] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:556; }","duration":"813.646546ms","start":"2026-03-06T22:00:55.229914Z","end":"2026-03-06T22:00:56.043561Z","steps":["trace[2001287282] 'read index received' (duration: 813.638466ms)","trace[2001287282] 'applied index is now lower than readState.Index' (duration: 6.55µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:00:56.043787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.714209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-06T22:00:56.043931Z","caller":"traceutil/trace.go:171","msg":"trace[1443735187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:536; }","duration":"813.874752ms","start":"2026-03-06T22:00:55.23004Z","end":"2026-03-06T22:00:56.043915Z","steps":["trace[1443735187] 'agreement among raft nodes before linearized reading' (duration: 813.633696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:56.043993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:00:55.230032Z","time spent":"813.946364ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T22:00:56.044043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.105018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-06T22:00:56.044142Z","caller":"traceutil/trace.go:171","msg":"trace[489191273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:536; }","duration":"814.265352ms","start":"2026-03-06T22:00:55.229839Z","end":"2026-03-06T22:00:56.044104Z","steps":["trace[489191273] 'agreement among raft nodes before linearized reading' (duration: 814.024206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:56.044194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:00:55.229819Z","time spent":"814.364435ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-06T22:00:58.446497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.030931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T22:00:58.446742Z","caller":"traceutil/trace.go:171","msg":"trace[1219420201] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"250.47424ms","start":"2026-03-06T22:00:58.196239Z","end":"2026-03-06T22:00:58.446713Z","steps":["trace[1219420201] 'process raft request' (duration: 134.029659ms)","trace[1219420201] 'compare' (duration: 115.804666ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:00:58.617171Z","caller":"traceutil/trace.go:171","msg":"trace[1989186562] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"348.219157ms","start":"2026-03-06T22:00:58.268918Z","end":"2026-03-06T22:00:58.617137Z","steps":["trace[1989186562] 'process raft request' (duration: 304.182244ms)","trace[1989186562] 'compare' (duration: 43.849289ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:00:58.617083Z","caller":"traceutil/trace.go:171","msg":"trace[849236430] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"165.984872ms","start":"2026-03-06T22:00:58.451076Z","end":"2026-03-06T22:00:58.617061Z","steps":["trace[849236430] 'read index received' (duration: 122.04025ms)","trace[849236430] 'applied index is now lower than readState.Index' (duration: 43.943772ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:00:58.617518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.355161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-pmctb\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-03-06T22:00:58.617578Z","caller":"traceutil/trace.go:171","msg":"trace[731734187] range","detail":"{range_begin:/registry/pods/kube-system/cilium-pmctb; range_end:; response_count:1; response_revision:568; }","duration":"166.557636ms","start":"2026-03-06T22:00:58.451004Z","end":"2026-03-06T22:00:58.617561Z","steps":["trace[731734187] 'agreement among raft nodes before linearized reading' (duration: 166.218578ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:58.617549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:00:58.268883Z","time spent":"348.488614ms","remote":"127.0.0.1:54752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-06T22:00:58.799425Z","caller":"traceutil/trace.go:171","msg":"trace[1885256160] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"331.370289ms","start":"2026-03-06T22:00:58.467995Z","end":"2026-03-06T22:00:58.799365Z","steps":["trace[1885256160] 'process raft request' (duration: 262.817567ms)","trace[1885256160] 'compare' (duration: 68.343357ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:00:58.799657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:00:58.467974Z","time spent":"331.584653ms","remote":"127.0.0.1:54752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-06T22:00:58.808407Z","caller":"traceutil/trace.go:171","msg":"trace[1331309065] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"182.029381ms","start":"2026-03-06T22:00:58.626354Z","end":"2026-03-06T22:00:58.808383Z","steps":["trace[1331309065] 'process raft request' (duration: 181.821875ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:00:58.80882Z","caller":"traceutil/trace.go:171","msg":"trace[433275966] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"177.959939ms","start":"2026-03-06T22:00:58.630828Z","end":"2026-03-06T22:00:58.808787Z","steps":["trace[433275966] 'process raft request' (duration: 177.494647ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:00:58.809101Z","caller":"traceutil/trace.go:171","msg":"trace[1007721412] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"136.686275ms","start":"2026-03-06T22:00:58.672398Z","end":"2026-03-06T22:00:58.809084Z","steps":["trace[1007721412] 'process raft request' (duration: 136.277205ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:08.363033Z","caller":"traceutil/trace.go:171","msg":"trace[1941632848] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"176.091783ms","start":"2026-03-06T22:01:08.186913Z","end":"2026-03-06T22:01:08.363004Z","steps":["trace[1941632848] 'process raft request' (duration: 86.041397ms)","trace[1941632848] 'compare' (duration: 89.937563ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:01:09.076583Z","caller":"traceutil/trace.go:171","msg":"trace[2092643151] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"137.326921ms","start":"2026-03-06T22:01:08.939226Z","end":"2026-03-06T22:01:09.076553Z","steps":["trace[2092643151] 'process raft request' (duration: 137.186228ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:09.191149Z","caller":"traceutil/trace.go:171","msg":"trace[972278975] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"103.201424ms","start":"2026-03-06T22:01:09.087928Z","end":"2026-03-06T22:01:09.19113Z","steps":["trace[972278975] 'process raft request' (duration: 96.760933ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:13.253282Z","caller":"traceutil/trace.go:171","msg":"trace[1191493192] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:744; }","duration":"196.909643ms","start":"2026-03-06T22:01:13.056356Z","end":"2026-03-06T22:01:13.253265Z","steps":["trace[1191493192] 'read index received' (duration: 196.847242ms)","trace[1191493192] 'applied index is now lower than readState.Index' (duration: 61.641µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:01:13.253305Z","caller":"traceutil/trace.go:171","msg":"trace[1143792524] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"323.280151ms","start":"2026-03-06T22:01:12.930009Z","end":"2026-03-06T22:01:13.253289Z","steps":["trace[1143792524] 'process raft request' (duration: 323.168518ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:13.253357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.001496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:01:13.253377Z","caller":"traceutil/trace.go:171","msg":"trace[1591722618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:719; }","duration":"197.067257ms","start":"2026-03-06T22:01:13.056303Z","end":"2026-03-06T22:01:13.25337Z","steps":["trace[1591722618] 'agreement among raft nodes before linearized reading' (duration: 197.025036ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:13.253388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:12.929973Z","time spent":"323.365822ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:01:14.706847Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051156221303544,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T22:01:15.207167Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051156221303544,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T22:01:15.295873Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.298287177s","expected-duration":"1s"} {"level":"info","ts":"2026-03-06T22:01:15.296204Z","caller":"traceutil/trace.go:171","msg":"trace[1748085079] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:745; }","duration":"1.090319799s","start":"2026-03-06T22:01:14.205862Z","end":"2026-03-06T22:01:15.296182Z","steps":["trace[1748085079] 'read index received' (duration: 1.090118334s)","trace[1748085079] 'applied index is now lower than readState.Index' (duration: 200.375µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:01:15.296248Z","caller":"traceutil/trace.go:171","msg":"trace[417791162] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"1.298710936s","start":"2026-03-06T22:01:13.997511Z","end":"2026-03-06T22:01:15.296222Z","steps":["trace[417791162] 'process raft request' (duration: 1.298534462s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:15.296366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.652869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-06T22:01:15.296398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:13.997497Z","time spent":"1.29883485s","remote":"127.0.0.1:54992","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-03-06T22:01:15.296417Z","caller":"traceutil/trace.go:171","msg":"trace[724691440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:720; }","duration":"239.741692ms","start":"2026-03-06T22:01:15.056663Z","end":"2026-03-06T22:01:15.296405Z","steps":["trace[724691440] 'agreement among raft nodes before linearized reading' (duration: 239.65705ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:15.296375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048283009s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-06T22:01:15.296456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.422166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-06T22:01:15.296497Z","caller":"traceutil/trace.go:171","msg":"trace[1172489891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:720; }","duration":"901.489228ms","start":"2026-03-06T22:01:14.394996Z","end":"2026-03-06T22:01:15.296486Z","steps":["trace[1172489891] 'agreement among raft nodes before linearized reading' (duration: 901.403626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:15.296519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:14.394979Z","time spent":"901.534748ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-06T22:01:15.296513Z","caller":"traceutil/trace.go:171","msg":"trace[1921677842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:720; }","duration":"1.048457764s","start":"2026-03-06T22:01:14.248027Z","end":"2026-03-06T22:01:15.296485Z","steps":["trace[1921677842] 'agreement among raft nodes before linearized reading' (duration: 1.048242148s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:15.296615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:14.24801Z","time spent":"1.048577896s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-06T22:01:15.296868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091008227s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-06T22:01:15.296913Z","caller":"traceutil/trace.go:171","msg":"trace[925660653] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:720; }","duration":"1.091090419s","start":"2026-03-06T22:01:14.205811Z","end":"2026-03-06T22:01:15.296901Z","steps":["trace[925660653] 'agreement among raft nodes before linearized reading' (duration: 1.090433493s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:15.29694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:14.20579Z","time spent":"1.0911432s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-06T22:01:16.558202Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051156221303548,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T22:01:17.058998Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051156221303548,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T22:01:17.361162Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.718187756s","expected-duration":"1s"} {"level":"info","ts":"2026-03-06T22:01:17.401968Z","caller":"traceutil/trace.go:171","msg":"trace[1312294140] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"2.102011054s","start":"2026-03-06T22:01:15.299881Z","end":"2026-03-06T22:01:17.401892Z","steps":["trace[1312294140] 'process raft request' (duration: 2.06145974s)","trace[1312294140] 'compare' (duration: 40.054981ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:01:17.402146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:15.299869Z","time spent":"2.102189668s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:01:18.058158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000749877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-06T22:01:18.058249Z","caller":"traceutil/trace.go:171","msg":"trace[925044496] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000884071s","start":"2026-03-06T22:01:16.057344Z","end":"2026-03-06T22:01:18.058228Z","steps":["trace[925044496] 'agreement among raft nodes before linearized reading' (duration: 2.000770298s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:18.058303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:16.057328Z","time spent":"2.000965632s","remote":"127.0.0.1:52046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T22:01:18.815971Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.454629334s","expected-duration":"1s"} {"level":"info","ts":"2026-03-06T22:01:18.852149Z","caller":"traceutil/trace.go:171","msg":"trace[1581849312] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"3.551836259s","start":"2026-03-06T22:01:15.300276Z","end":"2026-03-06T22:01:18.852112Z","steps":["trace[1581849312] 'process raft request' (duration: 3.515847789s)","trace[1581849312] 'compare' (duration: 35.16492ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:01:18.852268Z","caller":"traceutil/trace.go:171","msg":"trace[1290019241] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"3.551812428s","start":"2026-03-06T22:01:15.300419Z","end":"2026-03-06T22:01:18.852232Z","steps":["trace[1290019241] 'process raft request' (duration: 3.551016608s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:18.852325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:15.300267Z","time spent":"3.551969292s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:01:18.852476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:15.300411Z","time spent":"3.551962341s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:01:18.853033Z","caller":"traceutil/trace.go:171","msg":"trace[1805101343] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:746; }","duration":"2.795633137s","start":"2026-03-06T22:01:16.057382Z","end":"2026-03-06T22:01:18.853015Z","steps":["trace[1805101343] 'read index received' (duration: 1.303974896s)","trace[1805101343] 'applied index is now lower than readState.Index' (duration: 1.491656991s)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:01:18.853162Z","caller":"traceutil/trace.go:171","msg":"trace[746966481] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"1.648905462s","start":"2026-03-06T22:01:17.204243Z","end":"2026-03-06T22:01:18.853148Z","steps":["trace[746966481] 'process raft request' (duration: 1.648712387s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:18.853377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:17.204218Z","time spent":"1.649103756s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:01:18.853583Z","caller":"traceutil/trace.go:171","msg":"trace[1688197089] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"3.550944447s","start":"2026-03-06T22:01:15.302614Z","end":"2026-03-06T22:01:18.853558Z","steps":["trace[1688197089] 'process raft request' (duration: 3.55025789s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:18.853694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:15.302604Z","time spent":"3.551049659s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:01:18.853999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.857507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-06T22:01:18.854131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.968907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T22:01:18.854173Z","caller":"traceutil/trace.go:171","msg":"trace[2086498625] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:725; }","duration":"360.035809ms","start":"2026-03-06T22:01:18.494127Z","end":"2026-03-06T22:01:18.854163Z","steps":["trace[2086498625] 'agreement among raft nodes before linearized reading' (duration: 359.976967ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:18.8542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:18.494112Z","time spent":"360.080429ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-06T22:01:18.854005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.43345398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:01:18.854342Z","caller":"traceutil/trace.go:171","msg":"trace[2016357251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:725; }","duration":"1.43382574s","start":"2026-03-06T22:01:17.420507Z","end":"2026-03-06T22:01:18.854333Z","steps":["trace[2016357251] 'agreement among raft nodes before linearized reading' (duration: 1.433451841s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:18.854378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:17.420477Z","time spent":"1.433892442s","remote":"127.0.0.1:52046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T22:01:18.854064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.412562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-06T22:01:18.854447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.553383727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-06T22:01:18.854515Z","caller":"traceutil/trace.go:171","msg":"trace[1522281861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:725; }","duration":"795.861533ms","start":"2026-03-06T22:01:18.058644Z","end":"2026-03-06T22:01:18.854505Z","steps":["trace[1522281861] 'agreement among raft nodes before linearized reading' (duration: 795.396832ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:18.854484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.724944453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-06T22:01:18.854621Z","caller":"traceutil/trace.go:171","msg":"trace[761670407] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:725; }","duration":"1.725109817s","start":"2026-03-06T22:01:17.129491Z","end":"2026-03-06T22:01:18.854601Z","steps":["trace[761670407] 'agreement among raft nodes before linearized reading' (duration: 1.724783549s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:18.854547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:18.058603Z","time spent":"795.935185ms","remote":"127.0.0.1:52060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T22:01:18.854756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:17.129473Z","time spent":"1.725210539s","remote":"127.0.0.1:54724","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.227\" "} {"level":"info","ts":"2026-03-06T22:01:18.854551Z","caller":"traceutil/trace.go:171","msg":"trace[1907734787] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:725; }","duration":"1.553538052s","start":"2026-03-06T22:01:17.300985Z","end":"2026-03-06T22:01:18.854523Z","steps":["trace[1907734787] 'agreement among raft nodes before linearized reading' (duration: 1.55308012s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:18.854788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.927623328s","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-03-06T22:01:18.85487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:17.30097Z","time spent":"1.55388762s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-06T22:01:18.854885Z","caller":"traceutil/trace.go:171","msg":"trace[296237400] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:725; }","duration":"1.927807453s","start":"2026-03-06T22:01:16.927054Z","end":"2026-03-06T22:01:18.854862Z","steps":["trace[296237400] 'agreement among raft nodes before linearized reading' (duration: 1.927630758s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:18.854955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:16.927028Z","time spent":"1.927914245s","remote":"127.0.0.1:54896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":11,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-03-06T22:01:18.854665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.787952417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:01:18.855228Z","caller":"traceutil/trace.go:171","msg":"trace[1898476716] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:725; }","duration":"2.78853671s","start":"2026-03-06T22:01:16.066681Z","end":"2026-03-06T22:01:18.855217Z","steps":["trace[1898476716] 'agreement among raft nodes before linearized reading' (duration: 2.787957206s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:18.855261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:16.066664Z","time spent":"2.788586422s","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-03-06T22:01:18.855307Z","caller":"traceutil/trace.go:171","msg":"trace[1254472353] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:725; }","duration":"757.055002ms","start":"2026-03-06T22:01:18.097097Z","end":"2026-03-06T22:01:18.854152Z","steps":["trace[1254472353] 'agreement among raft nodes before linearized reading' (duration: 756.849877ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:18.85546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:18.097082Z","time spent":"758.351666ms","remote":"127.0.0.1:55252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-03-06T22:01:19.018926Z","caller":"traceutil/trace.go:171","msg":"trace[2035856404] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"160.05733ms","start":"2026-03-06T22:01:18.85884Z","end":"2026-03-06T22:01:19.018898Z","steps":["trace[2035856404] 'process raft request' (duration: 97.143563ms)","trace[2035856404] 'compare' (duration: 62.535598ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:01:19.019641Z","caller":"traceutil/trace.go:171","msg":"trace[1355417231] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:751; }","duration":"157.144629ms","start":"2026-03-06T22:01:18.862479Z","end":"2026-03-06T22:01:19.019624Z","steps":["trace[1355417231] 'read index received' (duration: 58.083788ms)","trace[1355417231] 'applied index is now lower than readState.Index' (duration: 99.058521ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:01:19.019763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.263402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:01:19.019804Z","caller":"traceutil/trace.go:171","msg":"trace[551833953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:727; }","duration":"157.339083ms","start":"2026-03-06T22:01:18.862455Z","end":"2026-03-06T22:01:19.019794Z","steps":["trace[551833953] 'agreement among raft nodes before linearized reading' (duration: 157.230841ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:19.229867Z","caller":"traceutil/trace.go:171","msg":"trace[790322796] linearizableReadLoop","detail":"{readStateIndex:756; appliedIndex:755; }","duration":"207.101565ms","start":"2026-03-06T22:01:19.02274Z","end":"2026-03-06T22:01:19.229842Z","steps":["trace[790322796] 'read index received' (duration: 171.691679ms)","trace[790322796] 'applied index is now lower than readState.Index' (duration: 35.408736ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:01:19.229879Z","caller":"traceutil/trace.go:171","msg":"trace[1707071359] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"208.351485ms","start":"2026-03-06T22:01:19.0215Z","end":"2026-03-06T22:01:19.229851Z","steps":["trace[1707071359] 'process raft request' (duration: 172.953009ms)","trace[1707071359] 'compare' (duration: 35.264963ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:01:19.230049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.293429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-06T22:01:19.230076Z","caller":"traceutil/trace.go:171","msg":"trace[972205451] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:728; }","duration":"207.355321ms","start":"2026-03-06T22:01:19.022714Z","end":"2026-03-06T22:01:19.23007Z","steps":["trace[972205451] 'agreement among raft nodes before linearized reading' (duration: 207.205028ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:20.556951Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051156221303569,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T22:01:21.058117Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051156221303569,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T22:01:21.558824Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051156221303569,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T22:01:21.633148Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.671508782s","expected-duration":"1s"} {"level":"info","ts":"2026-03-06T22:01:21.633686Z","caller":"traceutil/trace.go:171","msg":"trace[932965094] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"1.672132057s","start":"2026-03-06T22:01:19.961514Z","end":"2026-03-06T22:01:21.633646Z","steps":["trace[932965094] 'process raft request' (duration: 1.67186271s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:21.634034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:19.961492Z","time spent":"1.672424403s","remote":"127.0.0.1:54888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5170,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:01:22.056524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000105931s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-06T22:01:22.056599Z","caller":"traceutil/trace.go:171","msg":"trace[1056363163] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000237575s","start":"2026-03-06T22:01:20.056338Z","end":"2026-03-06T22:01:22.056576Z","steps":["trace[1056363163] 'agreement among raft nodes before linearized reading' (duration: 2.000135142s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:22.056649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:20.056318Z","time spent":"2.000316186s","remote":"127.0.0.1:52046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T22:01:22.259583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.095757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T22:01:22.25966Z","caller":"traceutil/trace.go:171","msg":"trace[1995352231] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:757; }","duration":"2.203261169s","start":"2026-03-06T22:01:20.056386Z","end":"2026-03-06T22:01:22.259647Z","steps":["trace[1995352231] 'read index received' (duration: 1.576928441s)","trace[1995352231] 'applied index is now lower than readState.Index' (duration: 626.331938ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:01:22.259756Z","caller":"traceutil/trace.go:171","msg":"trace[988280001] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"1.670301471s","start":"2026-03-06T22:01:20.589393Z","end":"2026-03-06T22:01:22.259695Z","steps":["trace[988280001] 'process raft request' (duration: 1.393023749s)","trace[988280001] 'compare' (duration: 277.002255ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:01:22.260038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:20.589368Z","time spent":"1.670536408s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:01:22.416632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.89453ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-06T22:01:22.41681Z","caller":"traceutil/trace.go:171","msg":"trace[435437418] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:759; }","duration":"157.109688ms","start":"2026-03-06T22:01:22.259677Z","end":"2026-03-06T22:01:22.416787Z","steps":["trace[435437418] 'read index received' (duration: 6.995963ms)","trace[435437418] 'applied index is now lower than readState.Index' (duration: 150.111715ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:01:22.416947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.079841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:01:22.41703Z","caller":"traceutil/trace.go:171","msg":"trace[386785072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:731; }","duration":"358.228574ms","start":"2026-03-06T22:01:22.05878Z","end":"2026-03-06T22:01:22.417009Z","steps":["trace[386785072] 'agreement among raft nodes before linearized reading' (duration: 358.07229ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:22.417074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:22.058768Z","time spent":"358.297416ms","remote":"127.0.0.1:52060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T22:01:22.41708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.558969586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-06T22:01:22.41706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.550818675s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:01:22.417116Z","caller":"traceutil/trace.go:171","msg":"trace[1210298134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:731; }","duration":"1.559069999s","start":"2026-03-06T22:01:20.858034Z","end":"2026-03-06T22:01:22.417104Z","steps":["trace[1210298134] 'agreement among raft nodes before linearized reading' (duration: 1.558865664s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:22.417103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.394809754s","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":"warn","ts":"2026-03-06T22:01:22.417169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:20.858002Z","time spent":"1.559151471s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-06T22:01:22.417161Z","caller":"traceutil/trace.go:171","msg":"trace[265938188] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:731; }","duration":"1.550977129s","start":"2026-03-06T22:01:20.866155Z","end":"2026-03-06T22:01:22.417132Z","steps":["trace[265938188] 'agreement among raft nodes before linearized reading' (duration: 1.550752164s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:22.417187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.655977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-06T22:01:22.41723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:20.866146Z","time spent":"1.551065821s","remote":"127.0.0.1:54938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-03-06T22:01:22.417236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.560964135s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-06T22:01:22.417221Z","caller":"traceutil/trace.go:171","msg":"trace[1094100583] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:731; }","duration":"1.394992869s","start":"2026-03-06T22:01:21.022201Z","end":"2026-03-06T22:01:22.417194Z","steps":["trace[1094100583] 'agreement among raft nodes before linearized reading' (duration: 1.39462359s)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:22.417292Z","caller":"traceutil/trace.go:171","msg":"trace[1548760905] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:731; }","duration":"1.561056618s","start":"2026-03-06T22:01:20.856219Z","end":"2026-03-06T22:01:22.417276Z","steps":["trace[1548760905] 'agreement among raft nodes before linearized reading' (duration: 1.560879853s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:22.41731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:21.022172Z","time spent":"1.395127612s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-06T22:01:22.417336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:20.856199Z","time spent":"1.56112668s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-06T22:01:22.417722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.558939845s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-06T22:01:22.41794Z","caller":"traceutil/trace.go:171","msg":"trace[1538789264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:731; }","duration":"1.559030067s","start":"2026-03-06T22:01:20.858729Z","end":"2026-03-06T22:01:22.417759Z","steps":["trace[1538789264] 'agreement among raft nodes before linearized reading' (duration: 1.558763311s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:22.417984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:20.858702Z","time spent":"1.559272383s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-06T22:01:22.417965Z","caller":"traceutil/trace.go:171","msg":"trace[1275103399] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:731; }","duration":"936.76684ms","start":"2026-03-06T22:01:21.480491Z","end":"2026-03-06T22:01:22.417258Z","steps":["trace[1275103399] 'agreement among raft nodes before linearized reading' (duration: 936.462743ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:22.418048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:21.480441Z","time spent":"937.58168ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-06T22:01:22.688208Z","caller":"traceutil/trace.go:171","msg":"trace[1589395953] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"268.234698ms","start":"2026-03-06T22:01:22.419948Z","end":"2026-03-06T22:01:22.688183Z","steps":["trace[1589395953] 'process raft request' (duration: 268.040413ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:22.911105Z","caller":"traceutil/trace.go:171","msg":"trace[309852383] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"488.872926ms","start":"2026-03-06T22:01:22.422201Z","end":"2026-03-06T22:01:22.911074Z","steps":["trace[309852383] 'process raft request' (duration: 407.086053ms)","trace[309852383] 'compare' (duration: 81.518437ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:01:22.911297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:22.422177Z","time spent":"489.021ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:01:22.911404Z","caller":"traceutil/trace.go:171","msg":"trace[863255157] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"487.859752ms","start":"2026-03-06T22:01:22.423521Z","end":"2026-03-06T22:01:22.911381Z","steps":["trace[863255157] 'process raft request' (duration: 487.494003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:22.911517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:22.423512Z","time spent":"487.938394ms","remote":"127.0.0.1:54992","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-03-06T22:01:22.911529Z","caller":"traceutil/trace.go:171","msg":"trace[1397959168] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"486.852717ms","start":"2026-03-06T22:01:22.424639Z","end":"2026-03-06T22:01:22.911491Z","steps":["trace[1397959168] 'process raft request' (duration: 486.58572ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:22.91175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:22.424622Z","time spent":"487.00213ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:01:22.911784Z","caller":"traceutil/trace.go:171","msg":"trace[1473852415] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"487.103143ms","start":"2026-03-06T22:01:22.424671Z","end":"2026-03-06T22:01:22.911774Z","steps":["trace[1473852415] 'process raft request' (duration: 486.766614ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:22.911754Z","caller":"traceutil/trace.go:171","msg":"trace[2139450571] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:760; }","duration":"486.979159ms","start":"2026-03-06T22:01:22.424744Z","end":"2026-03-06T22:01:22.911723Z","steps":["trace[2139450571] 'read index received' (duration: 263.155402ms)","trace[2139450571] 'applied index is now lower than readState.Index' (duration: 223.822747ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:01:22.911863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:22.424638Z","time spent":"487.198036ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:01:22.911918Z","caller":"traceutil/trace.go:171","msg":"trace[1257241139] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"486.638122ms","start":"2026-03-06T22:01:22.425269Z","end":"2026-03-06T22:01:22.911907Z","steps":["trace[1257241139] 'process raft request' (duration: 486.337713ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:22.911952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.209026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:01:22.911974Z","caller":"traceutil/trace.go:171","msg":"trace[455450815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:737; }","duration":"487.246427ms","start":"2026-03-06T22:01:22.424721Z","end":"2026-03-06T22:01:22.911967Z","steps":["trace[455450815] 'agreement among raft nodes before linearized reading' (duration: 487.206546ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:22.911992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:22.424714Z","time spent":"487.274478ms","remote":"127.0.0.1:52046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T22:01:22.912024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:22.425258Z","time spent":"486.687964ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:01:22.912115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.332252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.189a5f90cde37df5\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-03-06T22:01:22.912204Z","caller":"traceutil/trace.go:171","msg":"trace[77858334] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.189a5f90cde37df5; range_end:; response_count:1; response_revision:737; }","duration":"220.454925ms","start":"2026-03-06T22:01:22.69172Z","end":"2026-03-06T22:01:22.912175Z","steps":["trace[77858334] 'agreement among raft nodes before linearized reading' (duration: 220.23226ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:35.324412Z","caller":"traceutil/trace.go:171","msg":"trace[739153326] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"347.93641ms","start":"2026-03-06T22:01:34.976457Z","end":"2026-03-06T22:01:35.324394Z","steps":["trace[739153326] 'read index received' (duration: 347.848988ms)","trace[739153326] 'applied index is now lower than readState.Index' (duration: 86.662µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:01:35.324539Z","caller":"traceutil/trace.go:171","msg":"trace[341493759] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"348.418691ms","start":"2026-03-06T22:01:34.976089Z","end":"2026-03-06T22:01:35.324508Z","steps":["trace[341493759] 'process raft request' (duration: 348.166115ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:35.324601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.129204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-06T22:01:35.324633Z","caller":"traceutil/trace.go:171","msg":"trace[567973214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:772; }","duration":"348.183685ms","start":"2026-03-06T22:01:34.976439Z","end":"2026-03-06T22:01:35.324622Z","steps":["trace[567973214] 'agreement among raft nodes before linearized reading' (duration: 348.041252ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:35.324669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:34.976427Z","time spent":"348.232887ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T22:01:35.324714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:34.976073Z","time spent":"348.548454ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:01:35.360241Z","caller":"traceutil/trace.go:171","msg":"trace[795236041] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"383.326415ms","start":"2026-03-06T22:01:34.976888Z","end":"2026-03-06T22:01:35.360215Z","steps":["trace[795236041] 'process raft request' (duration: 377.193564ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:35.360236Z","caller":"traceutil/trace.go:171","msg":"trace[1976306443] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"383.344406ms","start":"2026-03-06T22:01:34.97687Z","end":"2026-03-06T22:01:35.360214Z","steps":["trace[1976306443] 'process raft request' (duration: 383.270934ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:35.360373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:34.97688Z","time spent":"383.422587ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:01:35.360405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:34.976858Z","time spent":"383.452078ms","remote":"127.0.0.1:54992","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-03-06T22:01:35.360451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.077629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:01:35.360483Z","caller":"traceutil/trace.go:171","msg":"trace[508051534] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"381.998732ms","start":"2026-03-06T22:01:34.978459Z","end":"2026-03-06T22:01:35.360458Z","steps":["trace[508051534] 'process raft request' (duration: 381.719805ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:35.360516Z","caller":"traceutil/trace.go:171","msg":"trace[766371730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"305.184411ms","start":"2026-03-06T22:01:35.055314Z","end":"2026-03-06T22:01:35.360499Z","steps":["trace[766371730] 'agreement among raft nodes before linearized reading' (duration: 305.045638ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:35.360557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:35.055295Z","time spent":"305.254733ms","remote":"127.0.0.1:52060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T22:01:35.360618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:34.978452Z","time spent":"382.096524ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:01:37.382102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.025014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T22:01:37.382253Z","caller":"traceutil/trace.go:171","msg":"trace[1021454549] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"184.182818ms","start":"2026-03-06T22:01:37.198051Z","end":"2026-03-06T22:01:37.382234Z","steps":["trace[1021454549] 'process raft request' (duration: 60.945558ms)","trace[1021454549] 'compare' (duration: 122.908772ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:01:37.614609Z","caller":"traceutil/trace.go:171","msg":"trace[714588173] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"228.582556ms","start":"2026-03-06T22:01:37.386001Z","end":"2026-03-06T22:01:37.614584Z","steps":["trace[714588173] 'process raft request' (duration: 215.528003ms)","trace[714588173] 'compare' (duration: 12.838638ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:01:37.614621Z","caller":"traceutil/trace.go:171","msg":"trace[1174533105] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"228.160616ms","start":"2026-03-06T22:01:37.386436Z","end":"2026-03-06T22:01:37.614597Z","steps":["trace[1174533105] 'process raft request' (duration: 228.082174ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:37.614634Z","caller":"traceutil/trace.go:171","msg":"trace[688626354] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"228.423413ms","start":"2026-03-06T22:01:37.386185Z","end":"2026-03-06T22:01:37.614609Z","steps":["trace[688626354] 'read index received' (duration: 215.360029ms)","trace[688626354] 'applied index is now lower than readState.Index' (duration: 13.061014ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:01:37.614751Z","caller":"traceutil/trace.go:171","msg":"trace[409276161] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"227.674784ms","start":"2026-03-06T22:01:37.387063Z","end":"2026-03-06T22:01:37.614738Z","steps":["trace[409276161] 'process raft request' (duration: 227.500409ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:37.614861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.658888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-06T22:01:37.614912Z","caller":"traceutil/trace.go:171","msg":"trace[1561043823] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:780; }","duration":"228.740461ms","start":"2026-03-06T22:01:37.386159Z","end":"2026-03-06T22:01:37.6149Z","steps":["trace[1561043823] 'agreement among raft nodes before linearized reading' (duration: 228.516785ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:37.614919Z","caller":"traceutil/trace.go:171","msg":"trace[1385848120] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"226.195867ms","start":"2026-03-06T22:01:37.388712Z","end":"2026-03-06T22:01:37.614907Z","steps":["trace[1385848120] 'process raft request' (duration: 225.971221ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:37.61494Z","caller":"traceutil/trace.go:171","msg":"trace[276337144] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"226.182627ms","start":"2026-03-06T22:01:37.388747Z","end":"2026-03-06T22:01:37.61493Z","steps":["trace[276337144] 'process raft request' (duration: 226.096765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:37.614943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.370089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:01:37.614987Z","caller":"traceutil/trace.go:171","msg":"trace[2004638673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"194.454521ms","start":"2026-03-06T22:01:37.420519Z","end":"2026-03-06T22:01:37.614974Z","steps":["trace[2004638673] 'agreement among raft nodes before linearized reading' (duration: 194.38225ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:41.754829Z","caller":"traceutil/trace.go:171","msg":"trace[1307876981] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"112.372871ms","start":"2026-03-06T22:01:41.642433Z","end":"2026-03-06T22:01:41.754805Z","steps":["trace[1307876981] 'process raft request' (duration: 112.296909ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:41.75491Z","caller":"traceutil/trace.go:171","msg":"trace[1815819239] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"112.459483ms","start":"2026-03-06T22:01:41.64235Z","end":"2026-03-06T22:01:41.75481Z","steps":["trace[1815819239] 'process raft request' (duration: 112.253908ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:41.755057Z","caller":"traceutil/trace.go:171","msg":"trace[361598720] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"112.519194ms","start":"2026-03-06T22:01:41.642525Z","end":"2026-03-06T22:01:41.755044Z","steps":["trace[361598720] 'process raft request' (duration: 112.243728ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:41.755339Z","caller":"traceutil/trace.go:171","msg":"trace[716672694] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"112.590635ms","start":"2026-03-06T22:01:41.642738Z","end":"2026-03-06T22:01:41.755329Z","steps":["trace[716672694] 'process raft request' (duration: 112.259017ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:43.555881Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051156221303739,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T22:01:44.056196Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051156221303739,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T22:01:44.557244Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051156221303739,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T22:01:45.055192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000045461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-06T22:01:45.055286Z","caller":"traceutil/trace.go:171","msg":"trace[1316243470] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000201055s","start":"2026-03-06T22:01:43.055068Z","end":"2026-03-06T22:01:45.055269Z","steps":["trace[1316243470] 'agreement among raft nodes before linearized reading' (duration: 2.000075792s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:45.055376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:43.055021Z","time spent":"2.000302547s","remote":"127.0.0.1:52046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/06 22:01:45 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-06T22:01:45.057721Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051156221303739,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T22:01:45.137693Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.484469257s","expected-duration":"1s"} {"level":"info","ts":"2026-03-06T22:01:45.138136Z","caller":"traceutil/trace.go:171","msg":"trace[1250612647] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:826; }","duration":"2.083006615s","start":"2026-03-06T22:01:43.055113Z","end":"2026-03-06T22:01:45.13812Z","steps":["trace[1250612647] 'read index received' (duration: 2.082751097s)","trace[1250612647] 'applied index is now lower than readState.Index' (duration: 254.888µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:01:45.138265Z","caller":"traceutil/trace.go:171","msg":"trace[1765779501] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"2.485166645s","start":"2026-03-06T22:01:42.653088Z","end":"2026-03-06T22:01:45.138254Z","steps":["trace[1765779501] 'process raft request' (duration: 2.484883908s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:45.138457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:42.65306Z","time spent":"2.485262258s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:01:45.138525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.378324747s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-06T22:01:45.138581Z","caller":"traceutil/trace.go:171","msg":"trace[1866956219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:794; }","duration":"1.37842118s","start":"2026-03-06T22:01:43.760147Z","end":"2026-03-06T22:01:45.138568Z","steps":["trace[1866956219] 'agreement among raft nodes before linearized reading' (duration: 1.378211114s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:45.138623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:43.760129Z","time spent":"1.378485901s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T22:01:45.138619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379082816s","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":"warn","ts":"2026-03-06T22:01:45.138663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.375308563s","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-03-06T22:01:45.13869Z","caller":"traceutil/trace.go:171","msg":"trace[1112267190] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:794; }","duration":"1.379204869s","start":"2026-03-06T22:01:43.759458Z","end":"2026-03-06T22:01:45.138663Z","steps":["trace[1112267190] 'agreement among raft nodes before linearized reading' (duration: 1.378996664s)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:45.138732Z","caller":"traceutil/trace.go:171","msg":"trace[1676994305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:794; }","duration":"1.375388854s","start":"2026-03-06T22:01:43.763324Z","end":"2026-03-06T22:01:45.138713Z","steps":["trace[1676994305] 'agreement among raft nodes before linearized reading' (duration: 1.37524073s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:45.138803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:43.763318Z","time spent":"1.375474396s","remote":"127.0.0.1:54992","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-03-06T22:01:45.138807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:43.759436Z","time spent":"1.379356623s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-06T22:01:45.138669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379049855s","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-03-06T22:01:45.13892Z","caller":"traceutil/trace.go:171","msg":"trace[775380309] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:794; }","duration":"1.379438925s","start":"2026-03-06T22:01:43.759469Z","end":"2026-03-06T22:01:45.138908Z","steps":["trace[775380309] 'agreement among raft nodes before linearized reading' (duration: 1.378927172s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:45.13897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:43.75945Z","time spent":"1.379496646s","remote":"127.0.0.1:54992","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-03-06T22:01:45.140079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.463526185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-06T22:01:45.140155Z","caller":"traceutil/trace.go:171","msg":"trace[1679774785] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:794; }","duration":"1.46454514s","start":"2026-03-06T22:01:43.675588Z","end":"2026-03-06T22:01:45.140133Z","steps":["trace[1679774785] 'agreement among raft nodes before linearized reading' (duration: 1.462742146s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:45.140232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:43.675569Z","time spent":"1.464644722s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-06T22:01:46.123122Z","caller":"traceutil/trace.go:171","msg":"trace[365181178] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"979.609229ms","start":"2026-03-06T22:01:45.143496Z","end":"2026-03-06T22:01:46.123105Z","steps":["trace[365181178] 'process raft request' (duration: 979.468425ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:46.123222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:45.143481Z","time spent":"979.689041ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:01:46.123361Z","caller":"traceutil/trace.go:171","msg":"trace[1286960981] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"979.401644ms","start":"2026-03-06T22:01:45.143942Z","end":"2026-03-06T22:01:46.123343Z","steps":["trace[1286960981] 'process raft request' (duration: 979.134248ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:46.123398Z","caller":"traceutil/trace.go:171","msg":"trace[802050614] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:828; }","duration":"977.20614ms","start":"2026-03-06T22:01:45.146175Z","end":"2026-03-06T22:01:46.123381Z","steps":["trace[802050614] 'read index received' (duration: 7.119576ms)","trace[802050614] 'applied index is now lower than readState.Index' (duration: 970.085834ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:01:46.123449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:45.143932Z","time spent":"979.467356ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:01:46.123477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.287102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:01:46.123496Z","caller":"traceutil/trace.go:171","msg":"trace[1572379001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:800; }","duration":"977.334673ms","start":"2026-03-06T22:01:45.146156Z","end":"2026-03-06T22:01:46.12349Z","steps":["trace[1572379001] 'agreement among raft nodes before linearized reading' (duration: 977.287012ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:46.123511Z","caller":"traceutil/trace.go:171","msg":"trace[947910920] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"979.072196ms","start":"2026-03-06T22:01:45.144431Z","end":"2026-03-06T22:01:46.123503Z","steps":["trace[947910920] 'process raft request' (duration: 978.82066ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:46.123538Z","caller":"traceutil/trace.go:171","msg":"trace[460927526] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"979.044675ms","start":"2026-03-06T22:01:45.144488Z","end":"2026-03-06T22:01:46.123533Z","steps":["trace[460927526] 'process raft request' (duration: 978.83986ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:46.123561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:45.144422Z","time spent":"979.110807ms","remote":"127.0.0.1:54798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:01:46.123593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:45.144414Z","time spent":"979.140158ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:01:46.123513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:45.146148Z","time spent":"977.360804ms","remote":"127.0.0.1:52046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-06T22:01:46.123738Z","caller":"traceutil/trace.go:171","msg":"trace[261353588] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"978.100562ms","start":"2026-03-06T22:01:45.145631Z","end":"2026-03-06T22:01:46.123731Z","steps":["trace[261353588] 'process raft request' (duration: 977.725083ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:46.123765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:45.145617Z","time spent":"978.132463ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:01:56.31161Z","caller":"traceutil/trace.go:171","msg":"trace[1136665620] linearizableReadLoop","detail":"{readStateIndex:860; appliedIndex:859; }","duration":"136.440926ms","start":"2026-03-06T22:01:56.175134Z","end":"2026-03-06T22:01:56.311575Z","steps":["trace[1136665620] 'read index received' (duration: 136.232421ms)","trace[1136665620] 'applied index is now lower than readState.Index' (duration: 207.345µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:01:56.311847Z","caller":"traceutil/trace.go:171","msg":"trace[1158553194] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"139.577054ms","start":"2026-03-06T22:01:56.172257Z","end":"2026-03-06T22:01:56.311834Z","steps":["trace[1158553194] 'process raft request' (duration: 139.177064ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:56.312141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.00941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-06T22:01:56.312219Z","caller":"traceutil/trace.go:171","msg":"trace[926843672] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:825; }","duration":"137.178045ms","start":"2026-03-06T22:01:56.175019Z","end":"2026-03-06T22:01:56.312197Z","steps":["trace[926843672] 'agreement among raft nodes before linearized reading' (duration: 137.067382ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:56.312377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.591955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-06T22:01:56.312449Z","caller":"traceutil/trace.go:171","msg":"trace[1464570739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:825; }","duration":"133.700027ms","start":"2026-03-06T22:01:56.178733Z","end":"2026-03-06T22:01:56.312433Z","steps":["trace[1464570739] 'agreement among raft nodes before linearized reading' (duration: 133.511023ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:56.312029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.693442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-06T22:01:56.312564Z","caller":"traceutil/trace.go:171","msg":"trace[1870609218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:825; }","duration":"137.292207ms","start":"2026-03-06T22:01:56.175258Z","end":"2026-03-06T22:01:56.312551Z","steps":["trace[1870609218] 'agreement among raft nodes before linearized reading' (duration: 136.616651ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:56.985085Z","caller":"traceutil/trace.go:171","msg":"trace[2072637662] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"668.620875ms","start":"2026-03-06T22:01:56.316435Z","end":"2026-03-06T22:01:56.985056Z","steps":["trace[2072637662] 'process raft request' (duration: 650.531257ms)","trace[2072637662] 'compare' (duration: 17.931274ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:01:56.985228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:56.316407Z","time spent":"668.745848ms","remote":"127.0.0.1:54992","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-03-06T22:01:57.167671Z","caller":"traceutil/trace.go:171","msg":"trace[648328061] linearizableReadLoop","detail":"{readStateIndex:863; appliedIndex:860; }","duration":"427.479659ms","start":"2026-03-06T22:01:56.74016Z","end":"2026-03-06T22:01:57.16764Z","steps":["trace[648328061] 'read index received' (duration: 226.919755ms)","trace[648328061] 'applied index is now lower than readState.Index' (duration: 200.558444ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:01:57.167686Z","caller":"traceutil/trace.go:171","msg":"trace[1455347074] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"850.641909ms","start":"2026-03-06T22:01:56.317004Z","end":"2026-03-06T22:01:57.167646Z","steps":["trace[1455347074] 'process raft request' (duration: 771.399667ms)","trace[1455347074] 'compare' (duration: 79.050137ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:01:57.167997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.792146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:01:57.168057Z","caller":"traceutil/trace.go:171","msg":"trace[1117542720] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:0; response_revision:828; }","duration":"427.92333ms","start":"2026-03-06T22:01:56.740116Z","end":"2026-03-06T22:01:57.168039Z","steps":["trace[1117542720] 'agreement among raft nodes before linearized reading' (duration: 427.787776ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:57.168097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:56.740092Z","time spent":"427.996451ms","remote":"127.0.0.1:54920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin\" "} {"level":"info","ts":"2026-03-06T22:01:57.168044Z","caller":"traceutil/trace.go:171","msg":"trace[1195154937] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"850.25303ms","start":"2026-03-06T22:01:56.317759Z","end":"2026-03-06T22:01:57.168012Z","steps":["trace[1195154937] 'process raft request' (duration: 849.82921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:57.168216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:56.316981Z","time spent":"850.917366ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:01:57.168305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.019118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:01:57.168574Z","caller":"traceutil/trace.go:171","msg":"trace[124392504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:828; }","duration":"111.49456ms","start":"2026-03-06T22:01:57.057037Z","end":"2026-03-06T22:01:57.168532Z","steps":["trace[124392504] 'agreement among raft nodes before linearized reading' (duration: 111.002028ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:57.168406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:56.317728Z","time spent":"850.437845ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:01:57.443331Z","caller":"traceutil/trace.go:171","msg":"trace[546458324] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"233.371755ms","start":"2026-03-06T22:01:57.20994Z","end":"2026-03-06T22:01:57.443312Z","steps":["trace[546458324] 'process raft request' (duration: 223.514702ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:01:57.443329Z","caller":"traceutil/trace.go:171","msg":"trace[888719868] linearizableReadLoop","detail":"{readStateIndex:865; appliedIndex:864; }","duration":"230.312949ms","start":"2026-03-06T22:01:57.212989Z","end":"2026-03-06T22:01:57.443302Z","steps":["trace[888719868] 'read index received' (duration: 220.410844ms)","trace[888719868] 'applied index is now lower than readState.Index' (duration: 9.900875ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:01:57.443758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.743359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/csi-rbd-secret\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:01:57.443805Z","caller":"traceutil/trace.go:171","msg":"trace[302374092] range","detail":"{range_begin:/registry/secrets/kube-system/csi-rbd-secret; range_end:; response_count:0; response_revision:829; }","duration":"230.839482ms","start":"2026-03-06T22:01:57.212951Z","end":"2026-03-06T22:01:57.443791Z","steps":["trace[302374092] 'agreement among raft nodes before linearized reading' (duration: 230.488823ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:57.443991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.188076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-06T22:01:57.44414Z","caller":"traceutil/trace.go:171","msg":"trace[1763249657] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:829; }","duration":"150.379721ms","start":"2026-03-06T22:01:57.293697Z","end":"2026-03-06T22:01:57.444076Z","steps":["trace[1763249657] 'agreement among raft nodes before linearized reading' (duration: 149.914929ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:59.777624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.160939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-06T22:01:59.777718Z","caller":"traceutil/trace.go:171","msg":"trace[400800015] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:884; }","duration":"604.302383ms","start":"2026-03-06T22:01:59.173394Z","end":"2026-03-06T22:01:59.777697Z","steps":["trace[400800015] 'range keys from in-memory index tree' (duration: 603.930504ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:59.777645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.20124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-06T22:01:59.77776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:59.17337Z","time spent":"604.381694ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-06T22:01:59.777825Z","caller":"traceutil/trace.go:171","msg":"trace[1769649710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:884; }","duration":"604.415975ms","start":"2026-03-06T22:01:59.173378Z","end":"2026-03-06T22:01:59.777794Z","steps":["trace[1769649710] 'range keys from in-memory index tree' (duration: 603.894062ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:59.777909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:01:59.173356Z","time spent":"604.53661ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-06T22:01:59.778329Z","caller":"traceutil/trace.go:171","msg":"trace[1829846039] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"155.278753ms","start":"2026-03-06T22:01:59.623037Z","end":"2026-03-06T22:01:59.778316Z","steps":["trace[1829846039] 'process raft request' (duration: 153.029507ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:01:59.778579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.107472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-06T22:01:59.778625Z","caller":"traceutil/trace.go:171","msg":"trace[337638302] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:884; }","duration":"274.987564ms","start":"2026-03-06T22:01:59.503626Z","end":"2026-03-06T22:01:59.778614Z","steps":["trace[337638302] 'range keys from in-memory index tree' (duration: 273.61862ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:01.440595Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051156221303958,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T22:02:01.940939Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051156221303958,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T22:02:02.107884Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.776172541s","expected-duration":"1s"} {"level":"info","ts":"2026-03-06T22:02:02.108352Z","caller":"traceutil/trace.go:171","msg":"trace[1156959136] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"1.168539625s","start":"2026-03-06T22:02:00.939782Z","end":"2026-03-06T22:02:02.108321Z","steps":["trace[1156959136] 'read index received' (duration: 1.168256128s)","trace[1156959136] 'applied index is now lower than readState.Index' (duration: 282.267µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:02:02.108474Z","caller":"traceutil/trace.go:171","msg":"trace[713170216] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"1.776799486s","start":"2026-03-06T22:02:00.331644Z","end":"2026-03-06T22:02:02.108444Z","steps":["trace[713170216] 'process raft request' (duration: 1.776526349s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:02.108531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168720889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T22:02:02.108575Z","caller":"traceutil/trace.go:171","msg":"trace[1769273211] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:889; }","duration":"1.168824042s","start":"2026-03-06T22:02:00.939739Z","end":"2026-03-06T22:02:02.108563Z","steps":["trace[1769273211] 'agreement among raft nodes before linearized reading' (duration: 1.168724059s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:02.108617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:00.939718Z","time spent":"1.168887434s","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-03-06T22:02:02.108618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:00.331634Z","time spent":"1.776896739s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:02:02.108683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051694325s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:02:02.108774Z","caller":"traceutil/trace.go:171","msg":"trace[283721575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:889; }","duration":"1.051831527s","start":"2026-03-06T22:02:01.056921Z","end":"2026-03-06T22:02:02.108753Z","steps":["trace[283721575] 'agreement among raft nodes before linearized reading' (duration: 1.051697874s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:02.10882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:01.056899Z","time spent":"1.05191295s","remote":"127.0.0.1:52046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T22:02:02.109085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.096868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-06T22:02:02.109092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.933992ms","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":"info","ts":"2026-03-06T22:02:02.109161Z","caller":"traceutil/trace.go:171","msg":"trace[650685319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:889; }","duration":"315.18957ms","start":"2026-03-06T22:02:01.793961Z","end":"2026-03-06T22:02:02.109151Z","steps":["trace[650685319] 'agreement among raft nodes before linearized reading' (duration: 315.029486ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:02.109184Z","caller":"traceutil/trace.go:171","msg":"trace[1782672587] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:889; }","duration":"496.032344ms","start":"2026-03-06T22:02:01.613133Z","end":"2026-03-06T22:02:02.109165Z","steps":["trace[1782672587] 'agreement among raft nodes before linearized reading' (duration: 495.896021ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:02.109194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030967451s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-06T22:02:02.109234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:01.613076Z","time spent":"496.147307ms","remote":"127.0.0.1:55254","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":"info","ts":"2026-03-06T22:02:02.109238Z","caller":"traceutil/trace.go:171","msg":"trace[90229606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:889; }","duration":"1.031038443s","start":"2026-03-06T22:02:01.078188Z","end":"2026-03-06T22:02:02.109227Z","steps":["trace[90229606] 'agreement among raft nodes before linearized reading' (duration: 1.030850778s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:02.109277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:01.078146Z","time spent":"1.031124455s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T22:02:02.109197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:01.79395Z","time spent":"315.239301ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-06T22:02:02.109426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.217823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-06T22:02:02.109467Z","caller":"traceutil/trace.go:171","msg":"trace[350026385] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:889; }","duration":"322.293975ms","start":"2026-03-06T22:02:01.787163Z","end":"2026-03-06T22:02:02.109457Z","steps":["trace[350026385] 'agreement among raft nodes before linearized reading' (duration: 322.175062ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:02.109497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:01.787144Z","time spent":"322.347306ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-06T22:02:02.109678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.636326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-06T22:02:02.109721Z","caller":"traceutil/trace.go:171","msg":"trace[290958447] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:889; }","duration":"316.744518ms","start":"2026-03-06T22:02:01.792966Z","end":"2026-03-06T22:02:02.109711Z","steps":["trace[290958447] 'agreement among raft nodes before linearized reading' (duration: 316.593934ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:02.109752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:01.792931Z","time spent":"316.815079ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-06T22:02:02.620506Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051156221303962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T22:02:03.120675Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051156221303962,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-06T22:02:03.186191Z","caller":"traceutil/trace.go:171","msg":"trace[486702940] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"1.069975556s","start":"2026-03-06T22:02:02.114221Z","end":"2026-03-06T22:02:03.184196Z","steps":["trace[486702940] 'process raft request' (duration: 1.0669269s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:03.186376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:02.114204Z","time spent":"1.072077358s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:02:03.22322Z","caller":"traceutil/trace.go:171","msg":"trace[1736469287] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"1.108703674s","start":"2026-03-06T22:02:02.11449Z","end":"2026-03-06T22:02:03.223193Z","steps":["trace[1736469287] 'process raft request' (duration: 1.108438097s)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:03.223219Z","caller":"traceutil/trace.go:171","msg":"trace[1378885725] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"1.108463258s","start":"2026-03-06T22:02:02.114724Z","end":"2026-03-06T22:02:03.223187Z","steps":["trace[1378885725] 'process raft request' (duration: 1.108371045s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:03.223342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:02.114469Z","time spent":"1.108808806s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:02:03.223394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:02.114712Z","time spent":"1.108585691s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:02:03.223525Z","caller":"traceutil/trace.go:171","msg":"trace[313276666] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"1.108624202s","start":"2026-03-06T22:02:02.114894Z","end":"2026-03-06T22:02:03.223518Z","steps":["trace[313276666] 'process raft request' (duration: 1.108250032s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:03.223569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:02.114864Z","time spent":"1.108680023s","remote":"127.0.0.1:54992","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-03-06T22:02:03.227129Z","caller":"traceutil/trace.go:171","msg":"trace[175848447] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:926; }","duration":"1.107517935s","start":"2026-03-06T22:02:02.119598Z","end":"2026-03-06T22:02:03.227116Z","steps":["trace[175848447] 'read index received' (duration: 1.061457695s)","trace[175848447] 'applied index is now lower than readState.Index' (duration: 46.05896ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:02:03.227215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107603948s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:02:03.227284Z","caller":"traceutil/trace.go:171","msg":"trace[1993973203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:893; }","duration":"1.10770671s","start":"2026-03-06T22:02:02.119563Z","end":"2026-03-06T22:02:03.22727Z","steps":["trace[1993973203] 'agreement among raft nodes before linearized reading' (duration: 1.107589477s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:03.227328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:02.119553Z","time spent":"1.107768641s","remote":"127.0.0.1:52046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-06T22:02:05.577274Z","caller":"traceutil/trace.go:171","msg":"trace[597613638] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"104.999049ms","start":"2026-03-06T22:02:05.472251Z","end":"2026-03-06T22:02:05.57725Z","steps":["trace[597613638] 'process raft request' (duration: 104.805924ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:07.386728Z","caller":"traceutil/trace.go:171","msg":"trace[1405792595] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"123.892317ms","start":"2026-03-06T22:02:07.262812Z","end":"2026-03-06T22:02:07.386704Z","steps":["trace[1405792595] 'process raft request' (duration: 123.834365ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:07.386772Z","caller":"traceutil/trace.go:171","msg":"trace[1372108448] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"124.235135ms","start":"2026-03-06T22:02:07.262512Z","end":"2026-03-06T22:02:07.386747Z","steps":["trace[1372108448] 'process raft request' (duration: 47.081815ms)","trace[1372108448] 'compare' (duration: 76.926374ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:02:09.949249Z","caller":"traceutil/trace.go:171","msg":"trace[260879655] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"151.752325ms","start":"2026-03-06T22:02:09.797462Z","end":"2026-03-06T22:02:09.949214Z","steps":["trace[260879655] 'process raft request' (duration: 151.55564ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:16.178223Z","caller":"traceutil/trace.go:171","msg":"trace[994845325] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"293.035481ms","start":"2026-03-06T22:02:15.885162Z","end":"2026-03-06T22:02:16.178197Z","steps":["trace[994845325] 'process raft request' (duration: 292.902788ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:16.178585Z","caller":"traceutil/trace.go:171","msg":"trace[430976823] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:967; }","duration":"121.865648ms","start":"2026-03-06T22:02:16.05671Z","end":"2026-03-06T22:02:16.178576Z","steps":["trace[430976823] 'read index received' (duration: 121.860517ms)","trace[430976823] 'applied index is now lower than readState.Index' (duration: 4.121µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:02:16.178713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.997511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:02:16.178744Z","caller":"traceutil/trace.go:171","msg":"trace[465358605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:927; }","duration":"122.078554ms","start":"2026-03-06T22:02:16.056658Z","end":"2026-03-06T22:02:16.178737Z","steps":["trace[465358605] 'agreement among raft nodes before linearized reading' (duration: 121.956881ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:16.632525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.709864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-06T22:02:16.632631Z","caller":"traceutil/trace.go:171","msg":"trace[969877156] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:927; }","duration":"395.878198ms","start":"2026-03-06T22:02:16.236722Z","end":"2026-03-06T22:02:16.6326Z","steps":["trace[969877156] 'range keys from in-memory index tree' (duration: 395.501669ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:16.632687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:16.236701Z","time spent":"395.97454ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-06T22:02:17.268346Z","caller":"traceutil/trace.go:171","msg":"trace[1298099572] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"364.638889ms","start":"2026-03-06T22:02:16.903682Z","end":"2026-03-06T22:02:17.268321Z","steps":["trace[1298099572] 'read index received' (duration: 363.755427ms)","trace[1298099572] 'applied index is now lower than readState.Index' (duration: 882.302µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:02:17.268447Z","caller":"traceutil/trace.go:171","msg":"trace[222465827] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"629.992681ms","start":"2026-03-06T22:02:16.638434Z","end":"2026-03-06T22:02:17.268427Z","steps":["trace[222465827] 'process raft request' (duration: 629.634282ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:17.268545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.852266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-06T22:02:17.268573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:16.638409Z","time spent":"630.087133ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:02:17.268665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.926086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:02:17.268626Z","caller":"traceutil/trace.go:171","msg":"trace[1258008679] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:928; }","duration":"134.934028ms","start":"2026-03-06T22:02:17.133634Z","end":"2026-03-06T22:02:17.268568Z","steps":["trace[1258008679] 'agreement among raft nodes before linearized reading' (duration: 134.814445ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:17.268808Z","caller":"traceutil/trace.go:171","msg":"trace[425070239] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:928; }","duration":"365.151262ms","start":"2026-03-06T22:02:16.903628Z","end":"2026-03-06T22:02:17.268779Z","steps":["trace[425070239] 'agreement among raft nodes before linearized reading' (duration: 364.843684ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:17.268881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:16.903606Z","time spent":"365.262184ms","remote":"127.0.0.1:54808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-03-06T22:02:17.537898Z","caller":"traceutil/trace.go:171","msg":"trace[325612246] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:969; }","duration":"139.426601ms","start":"2026-03-06T22:02:17.398454Z","end":"2026-03-06T22:02:17.53788Z","steps":["trace[325612246] 'read index received' (duration: 139.216086ms)","trace[325612246] 'applied index is now lower than readState.Index' (duration: 209.645µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:02:17.538054Z","caller":"traceutil/trace.go:171","msg":"trace[520833945] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"234.739319ms","start":"2026-03-06T22:02:17.303246Z","end":"2026-03-06T22:02:17.537986Z","steps":["trace[520833945] 'process raft request' (duration: 234.475812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:17.538171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.155519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T22:02:17.538207Z","caller":"traceutil/trace.go:171","msg":"trace[1327482432] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:929; }","duration":"138.18878ms","start":"2026-03-06T22:02:17.400005Z","end":"2026-03-06T22:02:17.538194Z","steps":["trace[1327482432] 'agreement among raft nodes before linearized reading' (duration: 138.137069ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:17.53811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.638926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-06T22:02:17.538434Z","caller":"traceutil/trace.go:171","msg":"trace[650138226] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:929; }","duration":"139.998545ms","start":"2026-03-06T22:02:17.398418Z","end":"2026-03-06T22:02:17.538416Z","steps":["trace[650138226] 'agreement among raft nodes before linearized reading' (duration: 139.566895ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:17.538749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.040857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:02:17.53878Z","caller":"traceutil/trace.go:171","msg":"trace[867703779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"119.114148ms","start":"2026-03-06T22:02:17.419656Z","end":"2026-03-06T22:02:17.53877Z","steps":["trace[867703779] 'agreement among raft nodes before linearized reading' (duration: 119.069377ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:17.539238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.325703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-06T22:02:17.539288Z","caller":"traceutil/trace.go:171","msg":"trace[1082058636] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:929; }","duration":"134.457806ms","start":"2026-03-06T22:02:17.404818Z","end":"2026-03-06T22:02:17.539276Z","steps":["trace[1082058636] 'agreement among raft nodes before linearized reading' (duration: 134.155158ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:20.524044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.174605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T22:02:20.524121Z","caller":"traceutil/trace.go:171","msg":"trace[86099272] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:959; }","duration":"120.654047ms","start":"2026-03-06T22:02:20.403448Z","end":"2026-03-06T22:02:20.524102Z","steps":["trace[86099272] 'count revisions from in-memory index tree' (duration: 120.023291ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:22.071872Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051156221304135,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-06T22:02:22.123304Z","caller":"traceutil/trace.go:171","msg":"trace[920242224] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"551.925289ms","start":"2026-03-06T22:02:21.571362Z","end":"2026-03-06T22:02:22.123287Z","steps":["trace[920242224] 'read index received' (duration: 551.777205ms)","trace[920242224] 'applied index is now lower than readState.Index' (duration: 147.504µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:02:22.123432Z","caller":"traceutil/trace.go:171","msg":"trace[2127596869] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"837.765078ms","start":"2026-03-06T22:02:21.2856Z","end":"2026-03-06T22:02:22.123365Z","steps":["trace[2127596869] 'process raft request' (duration: 837.583803ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:22.123497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.121104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-06T22:02:22.123521Z","caller":"traceutil/trace.go:171","msg":"trace[1409433766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:960; }","duration":"552.207317ms","start":"2026-03-06T22:02:21.571305Z","end":"2026-03-06T22:02:22.123512Z","steps":["trace[1409433766] 'agreement among raft nodes before linearized reading' (duration: 552.095844ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:22.12354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:21.571276Z","time spent":"552.259997ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T22:02:22.123599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:21.285586Z","time spent":"837.930742ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:02:22.123764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.33763ms","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-03-06T22:02:22.123783Z","caller":"traceutil/trace.go:171","msg":"trace[680195881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:960; }","duration":"552.35769ms","start":"2026-03-06T22:02:21.571421Z","end":"2026-03-06T22:02:22.123779Z","steps":["trace[680195881] 'agreement among raft nodes before linearized reading' (duration: 552.312129ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:22.123795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:21.571325Z","time spent":"552.467403ms","remote":"127.0.0.1:54992","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-03-06T22:02:22.124159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.53299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-06T22:02:22.124247Z","caller":"traceutil/trace.go:171","msg":"trace[1840010827] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:960; }","duration":"551.660652ms","start":"2026-03-06T22:02:21.572566Z","end":"2026-03-06T22:02:22.124227Z","steps":["trace[1840010827] 'agreement among raft nodes before linearized reading' (duration: 551.383806ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:22.124299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:21.572555Z","time spent":"551.734064ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-06T22:02:22.12419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.403255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-06T22:02:22.124491Z","caller":"traceutil/trace.go:171","msg":"trace[1824056602] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:960; }","duration":"551.758894ms","start":"2026-03-06T22:02:21.572709Z","end":"2026-03-06T22:02:22.124468Z","steps":["trace[1824056602] 'agreement among raft nodes before linearized reading' (duration: 551.282213ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:22.124608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:21.57269Z","time spent":"551.902879ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-06T22:02:22.865854Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051156221304139,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T22:02:23.366472Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051156221304139,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T22:02:23.790987Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.662356613s","expected-duration":"1s"} {"level":"info","ts":"2026-03-06T22:02:23.791468Z","caller":"traceutil/trace.go:171","msg":"trace[1711589533] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"1.662874927s","start":"2026-03-06T22:02:22.128563Z","end":"2026-03-06T22:02:23.791438Z","steps":["trace[1711589533] 'process raft request' (duration: 1.662687252s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:23.791924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:22.128551Z","time spent":"1.663073932s","remote":"127.0.0.1:54992","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-03-06T22:02:24.06856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.762109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T22:02:24.068751Z","caller":"traceutil/trace.go:171","msg":"trace[1129780893] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"1.938131498s","start":"2026-03-06T22:02:22.130584Z","end":"2026-03-06T22:02:24.068716Z","steps":["trace[1129780893] 'process raft request' (duration: 1.779129072s)","trace[1129780893] 'compare' (duration: 158.660846ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:02:24.068906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:22.130562Z","time spent":"1.938265482s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:02:24.068972Z","caller":"traceutil/trace.go:171","msg":"trace[897099763] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"1.938286442s","start":"2026-03-06T22:02:22.130671Z","end":"2026-03-06T22:02:24.068957Z","steps":["trace[897099763] 'process raft request' (duration: 1.937978904s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:24.069035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:22.130658Z","time spent":"1.938348583s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:02:24.069047Z","caller":"traceutil/trace.go:171","msg":"trace[135100112] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"1.938257911s","start":"2026-03-06T22:02:22.130753Z","end":"2026-03-06T22:02:24.069011Z","steps":["trace[135100112] 'process raft request' (duration: 1.937953074s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:24.069217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:22.130732Z","time spent":"1.938398614s","remote":"127.0.0.1:54992","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-03-06T22:02:24.358806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.040465ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-06T22:02:24.359104Z","caller":"traceutil/trace.go:171","msg":"trace[910782114] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1002; }","duration":"1.993703292s","start":"2026-03-06T22:02:22.365384Z","end":"2026-03-06T22:02:24.359087Z","steps":["trace[910782114] 'read index received' (duration: 1.42584131s)","trace[910782114] 'applied index is now lower than readState.Index' (duration: 567.861092ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:02:24.35914Z","caller":"traceutil/trace.go:171","msg":"trace[191901473] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"1.580798333s","start":"2026-03-06T22:02:22.778316Z","end":"2026-03-06T22:02:24.359115Z","steps":["trace[191901473] 'process raft request' (duration: 1.580614689s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:24.359252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:22.778286Z","time spent":"1.580903616s","remote":"127.0.0.1:54888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:02:24.359298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.393430881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T22:02:24.359351Z","caller":"traceutil/trace.go:171","msg":"trace[1294853283] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:966; }","duration":"1.393490412s","start":"2026-03-06T22:02:22.96585Z","end":"2026-03-06T22:02:24.35934Z","steps":["trace[1294853283] 'agreement among raft nodes before linearized reading' (duration: 1.393360569s)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:24.359355Z","caller":"traceutil/trace.go:171","msg":"trace[784303373] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"480.081164ms","start":"2026-03-06T22:02:23.879261Z","end":"2026-03-06T22:02:24.359342Z","steps":["trace[784303373] 'process raft request' (duration: 479.793527ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:24.359388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:22.96579Z","time spent":"1.393589545s","remote":"127.0.0.1:55064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-03-06T22:02:24.359401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.284314105s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-06T22:02:24.359383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.71724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:02:24.359432Z","caller":"traceutil/trace.go:171","msg":"trace[1424024830] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:966; }","duration":"1.284379157s","start":"2026-03-06T22:02:23.075042Z","end":"2026-03-06T22:02:24.359421Z","steps":["trace[1424024830] 'agreement among raft nodes before linearized reading' (duration: 1.284327826s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:24.359466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:23.074998Z","time spent":"1.284460689s","remote":"127.0.0.1:55048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-03-06T22:02:24.359472Z","caller":"traceutil/trace.go:171","msg":"trace[597261917] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:966; }","duration":"988.866254ms","start":"2026-03-06T22:02:23.370587Z","end":"2026-03-06T22:02:24.359453Z","steps":["trace[597261917] 'agreement among raft nodes before linearized reading' (duration: 988.731631ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:24.359516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.754902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-06T22:02:24.359527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:23.370566Z","time spent":"988.950207ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-03-06T22:02:24.359553Z","caller":"traceutil/trace.go:171","msg":"trace[213591109] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:966; }","duration":"229.826744ms","start":"2026-03-06T22:02:24.129718Z","end":"2026-03-06T22:02:24.359544Z","steps":["trace[213591109] 'agreement among raft nodes before linearized reading' (duration: 229.720302ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:24.359313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.701115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-06T22:02:24.359415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:23.879237Z","time spent":"480.146296ms","remote":"127.0.0.1:54798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":890,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-06T22:02:24.359625Z","caller":"traceutil/trace.go:171","msg":"trace[1287055164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:966; }","duration":"303.048834ms","start":"2026-03-06T22:02:24.056563Z","end":"2026-03-06T22:02:24.359612Z","steps":["trace[1287055164] 'agreement among raft nodes before linearized reading' (duration: 302.716046ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:24.359654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.921402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:02:24.359716Z","caller":"traceutil/trace.go:171","msg":"trace[2046661394] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:966; }","duration":"646.046406ms","start":"2026-03-06T22:02:23.713656Z","end":"2026-03-06T22:02:24.359702Z","steps":["trace[2046661394] 'agreement among raft nodes before linearized reading' (duration: 645.918013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:24.359748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:23.713618Z","time spent":"646.124727ms","remote":"127.0.0.1:54972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-03-06T22:02:24.359671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:24.056542Z","time spent":"303.118335ms","remote":"127.0.0.1:52046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T22:02:24.360078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.994650076s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T22:02:24.360148Z","caller":"traceutil/trace.go:171","msg":"trace[2126182961] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:966; }","duration":"1.99477179s","start":"2026-03-06T22:02:22.365365Z","end":"2026-03-06T22:02:24.360137Z","steps":["trace[2126182961] 'agreement among raft nodes before linearized reading' (duration: 1.993798875s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:24.360181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:22.365294Z","time spent":"1.994878182s","remote":"127.0.0.1:55154","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-03-06T22:02:24.674193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.217484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T22:02:24.674272Z","caller":"traceutil/trace.go:171","msg":"trace[1403256923] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"309.355713ms","start":"2026-03-06T22:02:24.364904Z","end":"2026-03-06T22:02:24.67426Z","steps":["trace[1403256923] 'process raft request' (duration: 163.070038ms)","trace[1403256923] 'compare' (duration: 146.042869ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:02:24.674308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:24.364892Z","time spent":"309.400365ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:02:25.978822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.144418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:02:25.978949Z","caller":"traceutil/trace.go:171","msg":"trace[977766394] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:967; }","duration":"273.337183ms","start":"2026-03-06T22:02:25.70559Z","end":"2026-03-06T22:02:25.978927Z","steps":["trace[977766394] 'count revisions from in-memory index tree' (duration: 273.031735ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:25.97885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.728844ms","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-03-06T22:02:25.979681Z","caller":"traceutil/trace.go:171","msg":"trace[808885727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:967; }","duration":"183.103375ms","start":"2026-03-06T22:02:25.796068Z","end":"2026-03-06T22:02:25.979171Z","steps":["trace[808885727] 'range keys from in-memory index tree' (duration: 182.569891ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:26.289753Z","caller":"traceutil/trace.go:171","msg":"trace[1081537555] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1010; }","duration":"233.514487ms","start":"2026-03-06T22:02:26.056221Z","end":"2026-03-06T22:02:26.289735Z","steps":["trace[1081537555] 'read index received' (duration: 233.393204ms)","trace[1081537555] 'applied index is now lower than readState.Index' (duration: 120.543µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:02:26.289836Z","caller":"traceutil/trace.go:171","msg":"trace[329712630] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"306.144101ms","start":"2026-03-06T22:02:25.983685Z","end":"2026-03-06T22:02:26.289829Z","steps":["trace[329712630] 'process raft request' (duration: 305.937166ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:26.289917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:25.983664Z","time spent":"306.194173ms","remote":"127.0.0.1:54992","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-03-06T22:02:26.290078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.040016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-06T22:02:26.290156Z","caller":"traceutil/trace.go:171","msg":"trace[943819863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:968; }","duration":"218.124559ms","start":"2026-03-06T22:02:26.072013Z","end":"2026-03-06T22:02:26.290138Z","steps":["trace[943819863] 'agreement among raft nodes before linearized reading' (duration: 217.935063ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:26.290287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.123654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-06T22:02:26.290332Z","caller":"traceutil/trace.go:171","msg":"trace[1199023958] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:968; }","duration":"217.204166ms","start":"2026-03-06T22:02:26.073117Z","end":"2026-03-06T22:02:26.290321Z","steps":["trace[1199023958] 'agreement among raft nodes before linearized reading' (duration: 217.065803ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:26.290472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-06T22:02:26.290513Z","caller":"traceutil/trace.go:171","msg":"trace[1597900035] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:968; }","duration":"218.232262ms","start":"2026-03-06T22:02:26.072271Z","end":"2026-03-06T22:02:26.290503Z","steps":["trace[1597900035] 'agreement among raft nodes before linearized reading' (duration: 218.054517ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:26.290787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.556043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:02:26.290867Z","caller":"traceutil/trace.go:171","msg":"trace[385597652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:968; }","duration":"234.669876ms","start":"2026-03-06T22:02:26.056178Z","end":"2026-03-06T22:02:26.290848Z","steps":["trace[385597652] 'agreement among raft nodes before linearized reading' (duration: 234.554823ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:27.59233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.375465ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T22:02:27.59283Z","caller":"traceutil/trace.go:171","msg":"trace[1692300474] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"1.297815048s","start":"2026-03-06T22:02:26.294969Z","end":"2026-03-06T22:02:27.592784Z","steps":["trace[1692300474] 'process raft request' (duration: 724.906398ms)","trace[1692300474] 'compare' (duration: 572.275683ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:02:27.592909Z","caller":"traceutil/trace.go:171","msg":"trace[293766620] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"1.297879819s","start":"2026-03-06T22:02:26.295012Z","end":"2026-03-06T22:02:27.592891Z","steps":["trace[293766620] 'process raft request' (duration: 1.297412397s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:27.592995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:26.29499Z","time spent":"1.297967991s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:02:27.593002Z","caller":"traceutil/trace.go:171","msg":"trace[84009985] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"1.297733235s","start":"2026-03-06T22:02:26.29525Z","end":"2026-03-06T22:02:27.592984Z","steps":["trace[84009985] 'process raft request' (duration: 1.297219962s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:27.593121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:26.295243Z","time spent":"1.297799077s","remote":"127.0.0.1:54992","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-03-06T22:02:27.593664Z","caller":"traceutil/trace.go:171","msg":"trace[528782990] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1011; }","duration":"915.502952ms","start":"2026-03-06T22:02:26.678145Z","end":"2026-03-06T22:02:27.593648Z","steps":["trace[528782990] 'read index received' (duration: 341.745042ms)","trace[528782990] 'applied index is now lower than readState.Index' (duration: 573.75656ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:02:27.593781Z","caller":"traceutil/trace.go:171","msg":"trace[404919409] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"1.050271475s","start":"2026-03-06T22:02:26.543494Z","end":"2026-03-06T22:02:27.593766Z","steps":["trace[404919409] 'process raft request' (duration: 1.049873065s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:27.593862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:26.543458Z","time spent":"1.050356697s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:02:27.593865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:26.29495Z","time spent":"1.298071243s","remote":"127.0.0.1:54992","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-03-06T22:02:27.593872Z","caller":"traceutil/trace.go:171","msg":"trace[1889808397] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"566.651401ms","start":"2026-03-06T22:02:27.027194Z","end":"2026-03-06T22:02:27.593845Z","steps":["trace[1889808397] 'process raft request' (duration: 566.411555ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:27.594021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.883451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-06T22:02:27.593898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.732065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:02:27.594064Z","caller":"traceutil/trace.go:171","msg":"trace[1389509291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:973; }","duration":"915.959973ms","start":"2026-03-06T22:02:26.678091Z","end":"2026-03-06T22:02:27.594051Z","steps":["trace[1389509291] 'agreement among raft nodes before linearized reading' (duration: 915.83819ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:27.594077Z","caller":"traceutil/trace.go:171","msg":"trace[681966619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"536.952441ms","start":"2026-03-06T22:02:27.05711Z","end":"2026-03-06T22:02:27.594062Z","steps":["trace[681966619] 'agreement among raft nodes before linearized reading' (duration: 536.700155ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:27.594103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:26.67807Z","time spent":"916.024574ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-06T22:02:27.594119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:27.057094Z","time spent":"537.014122ms","remote":"127.0.0.1:52046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T22:02:27.594114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:27.027162Z","time spent":"566.814485ms","remote":"127.0.0.1:54798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T22:02:27.594243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.687545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:02:27.594281Z","caller":"traceutil/trace.go:171","msg":"trace[774885106] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:973; }","duration":"479.760616ms","start":"2026-03-06T22:02:27.114512Z","end":"2026-03-06T22:02:27.594272Z","steps":["trace[774885106] 'agreement among raft nodes before linearized reading' (duration: 479.696265ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:27.594306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:27.114492Z","time spent":"479.808667ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-06T22:02:27.59457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.116239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-06T22:02:27.594622Z","caller":"traceutil/trace.go:171","msg":"trace[793510175] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:973; }","duration":"460.190432ms","start":"2026-03-06T22:02:27.134417Z","end":"2026-03-06T22:02:27.594608Z","steps":["trace[793510175] 'agreement among raft nodes before linearized reading' (duration: 459.763581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:27.595257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:27.134406Z","time spent":"460.835107ms","remote":"127.0.0.1:54724","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.227\" "} {"level":"info","ts":"2026-03-06T22:02:27.93528Z","caller":"traceutil/trace.go:171","msg":"trace[137927366] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"337.082734ms","start":"2026-03-06T22:02:27.59816Z","end":"2026-03-06T22:02:27.935243Z","steps":["trace[137927366] 'process raft request' (duration: 283.355507ms)","trace[137927366] 'compare' (duration: 53.376138ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:02:27.935426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:27.598142Z","time spent":"337.216667ms","remote":"127.0.0.1:54798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-06T22:02:27.935513Z","caller":"traceutil/trace.go:171","msg":"trace[1309483496] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"273.642041ms","start":"2026-03-06T22:02:27.661834Z","end":"2026-03-06T22:02:27.935476Z","steps":["trace[1309483496] 'process raft request' (duration: 273.550049ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:27.935618Z","caller":"traceutil/trace.go:171","msg":"trace[926400120] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"337.218037ms","start":"2026-03-06T22:02:27.598372Z","end":"2026-03-06T22:02:27.93559Z","steps":["trace[926400120] 'process raft request' (duration: 336.789156ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:27.935758Z","caller":"traceutil/trace.go:171","msg":"trace[1766750746] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1016; }","duration":"336.214571ms","start":"2026-03-06T22:02:27.599524Z","end":"2026-03-06T22:02:27.935738Z","steps":["trace[1766750746] 'read index received' (duration: 61.196426ms)","trace[1766750746] 'applied index is now lower than readState.Index' (duration: 275.015855ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:02:27.935786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:27.598308Z","time spent":"337.381581ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:02:27.935908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.361345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:02:27.935975Z","caller":"traceutil/trace.go:171","msg":"trace[1464750617] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:976; }","duration":"336.454117ms","start":"2026-03-06T22:02:27.599504Z","end":"2026-03-06T22:02:27.935958Z","steps":["trace[1464750617] 'agreement among raft nodes before linearized reading' (duration: 336.343284ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:27.93602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:27.599497Z","time spent":"336.512949ms","remote":"127.0.0.1:54808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-03-06T22:02:28.126704Z","caller":"traceutil/trace.go:171","msg":"trace[581975644] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"186.918461ms","start":"2026-03-06T22:02:27.939762Z","end":"2026-03-06T22:02:28.126681Z","steps":["trace[581975644] 'process raft request' (duration: 186.862829ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:28.126763Z","caller":"traceutil/trace.go:171","msg":"trace[746270233] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"187.381633ms","start":"2026-03-06T22:02:27.939348Z","end":"2026-03-06T22:02:28.126729Z","steps":["trace[746270233] 'read index received' (duration: 96.56535ms)","trace[746270233] 'applied index is now lower than readState.Index' (duration: 90.813133ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:02:28.127038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.6674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-06T22:02:28.127095Z","caller":"traceutil/trace.go:171","msg":"trace[331749906] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:978; }","duration":"187.794643ms","start":"2026-03-06T22:02:27.939287Z","end":"2026-03-06T22:02:28.127081Z","steps":["trace[331749906] 'agreement among raft nodes before linearized reading' (duration: 187.541356ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:28.127279Z","caller":"traceutil/trace.go:171","msg":"trace[547266866] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"189.355322ms","start":"2026-03-06T22:02:27.937885Z","end":"2026-03-06T22:02:28.12724Z","steps":["trace[547266866] 'process raft request' (duration: 98.706393ms)","trace[547266866] 'compare' (duration: 89.860469ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:02:28.452532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.622287ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T22:02:28.452936Z","caller":"traceutil/trace.go:171","msg":"trace[1842768783] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"317.852758ms","start":"2026-03-06T22:02:28.135059Z","end":"2026-03-06T22:02:28.452912Z","steps":["trace[1842768783] 'process raft request' (duration: 152.726718ms)","trace[1842768783] 'compare' (duration: 163.919889ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:02:28.453108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:28.135041Z","time spent":"317.95435ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:02:28.453316Z","caller":"traceutil/trace.go:171","msg":"trace[633764933] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1023; }","duration":"158.721958ms","start":"2026-03-06T22:02:28.293972Z","end":"2026-03-06T22:02:28.452694Z","steps":["trace[633764933] 'read index received' (duration: 52.531µs)","trace[633764933] 'applied index is now lower than readState.Index' (duration: 158.667377ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:02:28.453357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.352775ms","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-03-06T22:02:28.4535Z","caller":"traceutil/trace.go:171","msg":"trace[435490957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:980; }","duration":"159.605371ms","start":"2026-03-06T22:02:28.293844Z","end":"2026-03-06T22:02:28.453449Z","steps":["trace[435490957] 'agreement among raft nodes before linearized reading' (duration: 159.243852ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:30.437141Z","caller":"traceutil/trace.go:171","msg":"trace[1394568782] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"834.314841ms","start":"2026-03-06T22:02:29.602806Z","end":"2026-03-06T22:02:30.437121Z","steps":["trace[1394568782] 'process raft request' (duration: 834.196819ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:30.437316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:29.602776Z","time spent":"834.476375ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:02:30.961589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.975593ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T22:02:30.962025Z","caller":"traceutil/trace.go:171","msg":"trace[1047800804] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1025; }","duration":"1.020251856s","start":"2026-03-06T22:02:29.941747Z","end":"2026-03-06T22:02:30.961999Z","steps":["trace[1047800804] 'read index received' (duration: 495.325729ms)","trace[1047800804] 'applied index is now lower than readState.Index' (duration: 524.924707ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:02:30.962039Z","caller":"traceutil/trace.go:171","msg":"trace[169266048] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"1.357911995s","start":"2026-03-06T22:02:29.604086Z","end":"2026-03-06T22:02:30.961998Z","steps":["trace[169266048] 'process raft request' (duration: 895.871206ms)","trace[169266048] 'compare' (duration: 460.645214ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:02:30.962118Z","caller":"traceutil/trace.go:171","msg":"trace[956312875] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"1.357592106s","start":"2026-03-06T22:02:29.604465Z","end":"2026-03-06T22:02:30.962057Z","steps":["trace[956312875] 'process raft request' (duration: 1.3573614s)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:30.962216Z","caller":"traceutil/trace.go:171","msg":"trace[1548438931] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"839.07083ms","start":"2026-03-06T22:02:30.123081Z","end":"2026-03-06T22:02:30.962151Z","steps":["trace[1548438931] 'process raft request' (duration: 838.857005ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:30.962287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:29.60406Z","time spent":"1.35814033s","remote":"127.0.0.1:54992","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-03-06T22:02:30.962431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:30.123049Z","time spent":"839.256185ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:02:30.962501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.143304ms","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-03-06T22:02:30.962551Z","caller":"traceutil/trace.go:171","msg":"trace[1151872923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:985; }","duration":"413.258627ms","start":"2026-03-06T22:02:30.549281Z","end":"2026-03-06T22:02:30.96254Z","steps":["trace[1151872923] 'agreement among raft nodes before linearized reading' (duration: 413.009321ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:30.962598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:30.549262Z","time spent":"413.315068ms","remote":"127.0.0.1:54992","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-03-06T22:02:30.962715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020950084s","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-03-06T22:02:30.962787Z","caller":"traceutil/trace.go:171","msg":"trace[178370178] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:985; }","duration":"1.021093718s","start":"2026-03-06T22:02:29.941678Z","end":"2026-03-06T22:02:30.962771Z","steps":["trace[178370178] 'agreement among raft nodes before linearized reading' (duration: 1.020473092s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:30.962824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:29.941651Z","time spent":"1.02116363s","remote":"127.0.0.1:54992","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-03-06T22:02:30.963065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:29.604456Z","time spent":"1.35777625s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:02:31.774445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.595469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T22:02:31.774571Z","caller":"traceutil/trace.go:171","msg":"trace[1522507906] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"719.191182ms","start":"2026-03-06T22:02:31.055359Z","end":"2026-03-06T22:02:31.77455Z","steps":["trace[1522507906] 'read index received' (duration: 215.387959ms)","trace[1522507906] 'applied index is now lower than readState.Index' (duration: 503.801333ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:02:31.774654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.303756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:02:31.774686Z","caller":"traceutil/trace.go:171","msg":"trace[1179433377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"719.380127ms","start":"2026-03-06T22:02:31.055294Z","end":"2026-03-06T22:02:31.774674Z","steps":["trace[1179433377] 'agreement among raft nodes before linearized reading' (duration: 719.312556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:31.774728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:31.05527Z","time spent":"719.447919ms","remote":"127.0.0.1:52046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-06T22:02:31.774887Z","caller":"traceutil/trace.go:171","msg":"trace[2004286547] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"807.711349ms","start":"2026-03-06T22:02:30.967126Z","end":"2026-03-06T22:02:31.774838Z","steps":["trace[2004286547] 'process raft request' (duration: 303.619428ms)","trace[2004286547] 'compare' (duration: 502.886651ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:02:31.775135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:30.967097Z","time spent":"807.892243ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:02:33.394445Z","caller":"traceutil/trace.go:171","msg":"trace[117600690] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"417.575826ms","start":"2026-03-06T22:02:32.976834Z","end":"2026-03-06T22:02:33.39441Z","steps":["trace[117600690] 'process raft request' (duration: 417.32072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:33.394854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:32.97682Z","time spent":"417.832473ms","remote":"127.0.0.1:54992","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-03-06T22:02:34.93814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.273893513s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-06T22:02:34.938223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.326997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T22:02:34.938252Z","caller":"traceutil/trace.go:171","msg":"trace[1395123456] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:989; }","duration":"1.274020876s","start":"2026-03-06T22:02:33.664217Z","end":"2026-03-06T22:02:34.938238Z","steps":["trace[1395123456] 'range keys from in-memory index tree' (duration: 1.273871172s)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:34.938306Z","caller":"traceutil/trace.go:171","msg":"trace[1978313326] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1033; }","duration":"1.905151215s","start":"2026-03-06T22:02:33.033138Z","end":"2026-03-06T22:02:34.938289Z","steps":["trace[1978313326] 'read index received' (duration: 361.039797ms)","trace[1978313326] 'applied index is now lower than readState.Index' (duration: 1.544110188s)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:02:34.938518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.905380531s","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-03-06T22:02:34.938547Z","caller":"traceutil/trace.go:171","msg":"trace[401006613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:990; }","duration":"1.905449332s","start":"2026-03-06T22:02:33.033087Z","end":"2026-03-06T22:02:34.938536Z","steps":["trace[401006613] 'agreement among raft nodes before linearized reading' (duration: 1.905309839s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:34.938581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:33.033065Z","time spent":"1.905509654s","remote":"127.0.0.1:54992","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-03-06T22:02:34.938674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.882101494s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:02:34.938355Z","caller":"traceutil/trace.go:171","msg":"trace[1457814234] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"1.961429217s","start":"2026-03-06T22:02:32.976895Z","end":"2026-03-06T22:02:34.938324Z","steps":["trace[1457814234] 'process raft request' (duration: 1.066942306s)","trace[1457814234] 'compare' (duration: 894.049239ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:02:34.938773Z","caller":"traceutil/trace.go:171","msg":"trace[733854447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:990; }","duration":"1.882214096s","start":"2026-03-06T22:02:33.056534Z","end":"2026-03-06T22:02:34.938748Z","steps":["trace[733854447] 'agreement among raft nodes before linearized reading' (duration: 1.882044162s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:34.938826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:33.056506Z","time spent":"1.882309758s","remote":"127.0.0.1:52046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T22:02:34.938868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:32.976879Z","time spent":"1.961894059s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:02:34.938986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.753863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-06T22:02:34.939052Z","caller":"traceutil/trace.go:171","msg":"trace[1259096805] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:990; }","duration":"486.882267ms","start":"2026-03-06T22:02:34.452162Z","end":"2026-03-06T22:02:34.939044Z","steps":["trace[1259096805] 'agreement among raft nodes before linearized reading' (duration: 486.645001ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:34.939118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:34.452133Z","time spent":"486.957469ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-06T22:02:34.939188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158690013s","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-03-06T22:02:34.93923Z","caller":"traceutil/trace.go:171","msg":"trace[1876944537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:990; }","duration":"1.158764595s","start":"2026-03-06T22:02:33.780453Z","end":"2026-03-06T22:02:34.939218Z","steps":["trace[1876944537] 'agreement among raft nodes before linearized reading' (duration: 1.158587391s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:34.939259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:33.780433Z","time spent":"1.158818747s","remote":"127.0.0.1:54992","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-03-06T22:02:34.93931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.100995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T22:02:34.939349Z","caller":"traceutil/trace.go:171","msg":"trace[1549409427] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:990; }","duration":"440.182857ms","start":"2026-03-06T22:02:34.499155Z","end":"2026-03-06T22:02:34.939338Z","steps":["trace[1549409427] 'agreement among raft nodes before linearized reading' (duration: 440.115606ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:34.939394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:34.499129Z","time spent":"440.257129ms","remote":"127.0.0.1:48764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-03-06T22:02:34.939602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.480360447s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-03-06T22:02:34.939702Z","caller":"traceutil/trace.go:171","msg":"trace[712836714] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:990; }","duration":"1.48051102s","start":"2026-03-06T22:02:33.459177Z","end":"2026-03-06T22:02:34.939688Z","steps":["trace[712836714] 'agreement among raft nodes before linearized reading' (duration: 1.480139951s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:34.939777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:33.459159Z","time spent":"1.480606283s","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-06T22:02:37.679698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.73645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:02:37.68061Z","caller":"traceutil/trace.go:171","msg":"trace[508722449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1014; }","duration":"258.233092ms","start":"2026-03-06T22:02:37.421904Z","end":"2026-03-06T22:02:37.680137Z","steps":["trace[508722449] 'range keys from in-memory index tree' (duration: 257.659717ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:37.680638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.137626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-06T22:02:37.680919Z","caller":"traceutil/trace.go:171","msg":"trace[2036872608] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1014; }","duration":"267.998979ms","start":"2026-03-06T22:02:37.412878Z","end":"2026-03-06T22:02:37.680877Z","steps":["trace[2036872608] 'range keys from in-memory index tree' (duration: 266.741446ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:51.215913Z","caller":"traceutil/trace.go:171","msg":"trace[158221159] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"187.589217ms","start":"2026-03-06T22:02:51.02828Z","end":"2026-03-06T22:02:51.21587Z","steps":["trace[158221159] 'process raft request' (duration: 187.321491ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:51.362779Z","caller":"traceutil/trace.go:171","msg":"trace[202077818] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1131; }","duration":"317.648592ms","start":"2026-03-06T22:02:51.045104Z","end":"2026-03-06T22:02:51.362752Z","steps":["trace[202077818] 'read index received' (duration: 170.477035ms)","trace[202077818] 'applied index is now lower than readState.Index' (duration: 147.159507ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:02:51.363024Z","caller":"traceutil/trace.go:171","msg":"trace[1192508685] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"334.488469ms","start":"2026-03-06T22:02:51.028394Z","end":"2026-03-06T22:02:51.362883Z","steps":["trace[1192508685] 'process raft request' (duration: 327.075971ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:51.363199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.063563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-06T22:02:51.36329Z","caller":"traceutil/trace.go:171","msg":"trace[1246983589] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1085; }","duration":"318.192826ms","start":"2026-03-06T22:02:51.045076Z","end":"2026-03-06T22:02:51.363268Z","steps":["trace[1246983589] 'agreement among raft nodes before linearized reading' (duration: 317.909829ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:51.363341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:51.045062Z","time spent":"318.269228ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-06T22:02:51.36326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:51.028362Z","time spent":"334.704553ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:02:51.398919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.44326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:02:51.399003Z","caller":"traceutil/trace.go:171","msg":"trace[246731717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1086; }","duration":"342.546092ms","start":"2026-03-06T22:02:51.056442Z","end":"2026-03-06T22:02:51.398988Z","steps":["trace[246731717] 'agreement among raft nodes before linearized reading' (duration: 342.425658ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:02:51.399049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:02:51.05643Z","time spent":"342.611464ms","remote":"127.0.0.1:52046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T22:02:51.399309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.505847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-06T22:02:51.399421Z","caller":"traceutil/trace.go:171","msg":"trace[1894706230] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1086; }","duration":"290.686562ms","start":"2026-03-06T22:02:51.108707Z","end":"2026-03-06T22:02:51.399393Z","steps":["trace[1894706230] 'agreement among raft nodes before linearized reading' (duration: 290.315843ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:51.507309Z","caller":"traceutil/trace.go:171","msg":"trace[704776619] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"103.454213ms","start":"2026-03-06T22:02:51.403752Z","end":"2026-03-06T22:02:51.507206Z","steps":["trace[704776619] 'process raft request' (duration: 103.402102ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:02:51.507321Z","caller":"traceutil/trace.go:171","msg":"trace[54252919] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"138.829596ms","start":"2026-03-06T22:02:51.368453Z","end":"2026-03-06T22:02:51.507282Z","steps":["trace[54252919] 'process raft request' (duration: 97.206925ms)","trace[54252919] 'compare' (duration: 41.343414ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:02:51.507582Z","caller":"traceutil/trace.go:171","msg":"trace[1546603846] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"107.181926ms","start":"2026-03-06T22:02:51.400354Z","end":"2026-03-06T22:02:51.507536Z","steps":["trace[1546603846] 'read index received' (duration: 65.32334ms)","trace[1546603846] 'applied index is now lower than readState.Index' (duration: 41.856886ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:02:51.507753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.370511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:02:51.507791Z","caller":"traceutil/trace.go:171","msg":"trace[732673969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1088; }","duration":"107.473513ms","start":"2026-03-06T22:02:51.400309Z","end":"2026-03-06T22:02:51.507782Z","steps":["trace[732673969] 'agreement among raft nodes before linearized reading' (duration: 107.373071ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:15.592247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.305575ms","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-03-06T22:03:15.592365Z","caller":"traceutil/trace.go:171","msg":"trace[1375947951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1184; }","duration":"125.473309ms","start":"2026-03-06T22:03:15.466865Z","end":"2026-03-06T22:03:15.592338Z","steps":["trace[1375947951] 'range keys from in-memory index tree' (duration: 125.135511ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:03:15.592571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.30022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:53450"} {"level":"info","ts":"2026-03-06T22:03:15.593156Z","caller":"traceutil/trace.go:171","msg":"trace[1665300361] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1184; }","duration":"145.970376ms","start":"2026-03-06T22:03:15.447145Z","end":"2026-03-06T22:03:15.593116Z","steps":["trace[1665300361] 'range keys from in-memory index tree' (duration: 144.916411ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:40.314553Z","caller":"traceutil/trace.go:171","msg":"trace[570791775] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"119.600677ms","start":"2026-03-06T22:03:40.194921Z","end":"2026-03-06T22:03:40.314521Z","steps":["trace[570791775] 'process raft request' (duration: 119.442123ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:49.407102Z","caller":"traceutil/trace.go:171","msg":"trace[34628626] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"109.831671ms","start":"2026-03-06T22:03:49.297239Z","end":"2026-03-06T22:03:49.407071Z","steps":["trace[34628626] 'process raft request' (duration: 109.682216ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:03:57.755479Z","caller":"traceutil/trace.go:171","msg":"trace[294055985] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"119.278198ms","start":"2026-03-06T22:03:57.636174Z","end":"2026-03-06T22:03:57.755452Z","steps":["trace[294055985] 'process raft request' (duration: 119.129144ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:04:02.631457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.690725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T22:04:02.631678Z","caller":"traceutil/trace.go:171","msg":"trace[1263162187] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"159.304948ms","start":"2026-03-06T22:04:02.47234Z","end":"2026-03-06T22:04:02.631645Z","steps":["trace[1263162187] 'process raft request' (duration: 49.331535ms)","trace[1263162187] 'compare' (duration: 109.569933ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:04:02.9785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.859308ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-06T22:04:02.978627Z","caller":"traceutil/trace.go:171","msg":"trace[1239985252] linearizableReadLoop","detail":"{readStateIndex:1440; appliedIndex:1438; }","duration":"492.311776ms","start":"2026-03-06T22:04:02.486292Z","end":"2026-03-06T22:04:02.978604Z","steps":["trace[1239985252] 'read index received' (duration: 35.387723ms)","trace[1239985252] 'applied index is now lower than readState.Index' (duration: 456.922073ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:04:02.978991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.534089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-06T22:04:02.979066Z","caller":"traceutil/trace.go:171","msg":"trace[133949150] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1377; }","duration":"415.674953ms","start":"2026-03-06T22:04:02.56337Z","end":"2026-03-06T22:04:02.979045Z","steps":["trace[133949150] 'agreement among raft nodes before linearized reading' (duration: 415.406306ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:04:02.979115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:04:02.563351Z","time spent":"415.750675ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-06T22:04:02.979205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.907261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-03-06T22:04:02.979272Z","caller":"traceutil/trace.go:171","msg":"trace[2071549006] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1377; }","duration":"492.997003ms","start":"2026-03-06T22:04:02.486255Z","end":"2026-03-06T22:04:02.979252Z","steps":["trace[2071549006] 'agreement among raft nodes before linearized reading' (duration: 492.403428ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:04:02.979315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:04:02.486237Z","time spent":"493.065755ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-06T22:04:03.199176Z","caller":"traceutil/trace.go:171","msg":"trace[1511613756] linearizableReadLoop","detail":"{readStateIndex:1442; appliedIndex:1440; }","duration":"142.184086ms","start":"2026-03-06T22:04:03.056968Z","end":"2026-03-06T22:04:03.199152Z","steps":["trace[1511613756] 'read index received' (duration: 141.90968ms)","trace[1511613756] 'applied index is now lower than readState.Index' (duration: 273.496µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:04:03.199193Z","caller":"traceutil/trace.go:171","msg":"trace[2077908467] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"215.077815ms","start":"2026-03-06T22:04:02.984084Z","end":"2026-03-06T22:04:03.199162Z","steps":["trace[2077908467] 'process raft request' (duration: 214.854039ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:04:03.199215Z","caller":"traceutil/trace.go:171","msg":"trace[386529191] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"215.074604ms","start":"2026-03-06T22:04:02.984105Z","end":"2026-03-06T22:04:03.19918Z","steps":["trace[386529191] 'process raft request' (duration: 214.985852ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:04:03.199281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.295239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:04:03.199335Z","caller":"traceutil/trace.go:171","msg":"trace[1988029660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1379; }","duration":"142.382211ms","start":"2026-03-06T22:04:03.056927Z","end":"2026-03-06T22:04:03.19931Z","steps":["trace[1988029660] 'agreement among raft nodes before linearized reading' (duration: 142.31297ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:04:03.884855Z","caller":"traceutil/trace.go:171","msg":"trace[627352914] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"100.386071ms","start":"2026-03-06T22:04:03.784425Z","end":"2026-03-06T22:04:03.884811Z","steps":["trace[627352914] 'process raft request' (duration: 100.112444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:04:35.604493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.156572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-03-06T22:04:35.604558Z","caller":"traceutil/trace.go:171","msg":"trace[1320283373] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1510; }","duration":"103.297305ms","start":"2026-03-06T22:04:35.501245Z","end":"2026-03-06T22:04:35.604542Z","steps":["trace[1320283373] 'range keys from in-memory index tree' (duration: 102.955227ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:04:44.804133Z","caller":"traceutil/trace.go:171","msg":"trace[1118172503] linearizableReadLoop","detail":"{readStateIndex:1616; appliedIndex:1615; }","duration":"373.637169ms","start":"2026-03-06T22:04:44.430474Z","end":"2026-03-06T22:04:44.804111Z","steps":["trace[1118172503] 'read index received' (duration: 373.501006ms)","trace[1118172503] 'applied index is now lower than readState.Index' (duration: 135.083µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:04:44.804209Z","caller":"traceutil/trace.go:171","msg":"trace[436294143] transaction","detail":"{read_only:false; response_revision:1545; number_of_response:1; }","duration":"458.596238ms","start":"2026-03-06T22:04:44.345574Z","end":"2026-03-06T22:04:44.80417Z","steps":["trace[436294143] 'process raft request' (duration: 458.376732ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:04:44.804535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:04:44.345535Z","time spent":"458.947738ms","remote":"127.0.0.1:54992","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-03-06T22:04:44.804365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.867855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-06T22:04:44.804731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.796663ms","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-03-06T22:04:44.804766Z","caller":"traceutil/trace.go:171","msg":"trace[631974262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1545; }","duration":"304.826533ms","start":"2026-03-06T22:04:44.499927Z","end":"2026-03-06T22:04:44.804753Z","steps":["trace[631974262] 'agreement among raft nodes before linearized reading' (duration: 304.722671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:04:44.804794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:04:44.499873Z","time spent":"304.914325ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-06T22:04:44.804831Z","caller":"traceutil/trace.go:171","msg":"trace[1033976538] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1545; }","duration":"374.280555ms","start":"2026-03-06T22:04:44.430441Z","end":"2026-03-06T22:04:44.804722Z","steps":["trace[1033976538] 'agreement among raft nodes before linearized reading' (duration: 373.798353ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:04:44.804911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:04:44.430427Z","time spent":"374.45114ms","remote":"127.0.0.1:54992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-06T22:04:47.35652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.175429ms","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-03-06T22:04:47.356606Z","caller":"traceutil/trace.go:171","msg":"trace[1700073014] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1558; }","duration":"199.299192ms","start":"2026-03-06T22:04:47.157288Z","end":"2026-03-06T22:04:47.356587Z","steps":["trace[1700073014] 'range keys from in-memory index tree' (duration: 199.071987ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:05:58.554251Z","caller":"traceutil/trace.go:171","msg":"trace[462550656] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"132.708967ms","start":"2026-03-06T22:05:58.421507Z","end":"2026-03-06T22:05:58.554216Z","steps":["trace[462550656] 'process raft request' (duration: 132.42155ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:06:22.293591Z","caller":"traceutil/trace.go:171","msg":"trace[228179725] linearizableReadLoop","detail":"{readStateIndex:2076; appliedIndex:2075; }","duration":"116.263937ms","start":"2026-03-06T22:06:22.177297Z","end":"2026-03-06T22:06:22.293561Z","steps":["trace[228179725] 'read index received' (duration: 58.780929ms)","trace[228179725] 'applied index is now lower than readState.Index' (duration: 57.481318ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:06:22.293751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.432592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:1 size:201"} {"level":"info","ts":"2026-03-06T22:06:22.293823Z","caller":"traceutil/trace.go:171","msg":"trace[843002565] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:1; response_revision:1984; }","duration":"116.545704ms","start":"2026-03-06T22:06:22.177259Z","end":"2026-03-06T22:06:22.293804Z","steps":["trace[843002565] 'agreement among raft nodes before linearized reading' (duration: 116.404481ms)"],"step_count":1}