{"level":"warn","ts":"2026-02-24T04:35:07.231386Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T04:35:07.231476Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.141: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.141:2380","--initial-cluster=instance=https://199.204.45.141:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.141:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.141:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-24T04:35:07.231542Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T04:35:07.231554Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.141:2380"]} {"level":"info","ts":"2026-02-24T04:35:07.231568Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-24T04:35:07.232201Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"]} {"level":"info","ts":"2026-02-24T04:35:07.232268Z","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.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.141:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-24T04:35:07.236901Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.431739ms"} {"level":"info","ts":"2026-02-24T04:35:07.283056Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9ef3b4f68930dc2","cluster-id":"b19a5957e6e65747"} {"level":"info","ts":"2026-02-24T04:35:07.283166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T04:35:07.283208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 0"} {"level":"info","ts":"2026-02-24T04:35:07.283227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9ef3b4f68930dc2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T04:35:07.28325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 1"} {"level":"info","ts":"2026-02-24T04:35:07.283307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"} {"level":"warn","ts":"2026-02-24T04:35:07.301462Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T04:35:07.303615Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T04:35:07.30471Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-24T04:35:07.307608Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9ef3b4f68930dc2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T04:35:07.307858Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-24T04:35:07.307964Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-24T04:35:07.307984Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-24T04:35:07.307945Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9ef3b4f68930dc2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T04:35:07.312163Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-24T04:35:07.31235Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9ef3b4f68930dc2","initial-advertise-peer-urls":["https://199.204.45.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T04:35:07.312382Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T04:35:07.312414Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.141:2380"} {"level":"info","ts":"2026-02-24T04:35:07.312435Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.141:2380"} {"level":"info","ts":"2026-02-24T04:35:07.314207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"} {"level":"info","ts":"2026-02-24T04:35:07.314286Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","added-peer-id":"9ef3b4f68930dc2","added-peer-peer-urls":["https://199.204.45.141:2380"]} {"level":"info","ts":"2026-02-24T04:35:07.783967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T04:35:07.784028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T04:35:07.784043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgPreVoteResp from 9ef3b4f68930dc2 at term 1"} {"level":"info","ts":"2026-02-24T04:35:07.784053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became candidate at term 2"} {"level":"info","ts":"2026-02-24T04:35:07.784059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgVoteResp from 9ef3b4f68930dc2 at term 2"} {"level":"info","ts":"2026-02-24T04:35:07.784067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became leader at term 2"} {"level":"info","ts":"2026-02-24T04:35:07.784074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9ef3b4f68930dc2 elected leader 9ef3b4f68930dc2 at term 2"} {"level":"info","ts":"2026-02-24T04:35:07.784914Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9ef3b4f68930dc2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.141:2379]}","request-path":"/0/members/9ef3b4f68930dc2/attributes","cluster-id":"b19a5957e6e65747","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T04:35:07.785078Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T04:35:07.785265Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T04:35:07.785299Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T04:35:07.785391Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:35:07.785131Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T04:35:07.788747Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T04:35:07.789081Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.141:2379"} {"level":"info","ts":"2026-02-24T04:35:07.79665Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:35:07.799064Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:35:07.799094Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:35:23.865714Z","caller":"traceutil/trace.go:171","msg":"trace[617280383] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"174.163471ms","start":"2026-02-24T04:35:23.691519Z","end":"2026-02-24T04:35:23.865682Z","steps":["trace[617280383] 'process raft request' (duration: 173.972576ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:25.206229Z","caller":"traceutil/trace.go:171","msg":"trace[254950149] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"710.944936ms","start":"2026-02-24T04:35:24.49527Z","end":"2026-02-24T04:35:25.206215Z","steps":["trace[254950149] 'process raft request' (duration: 710.728391ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:25.206163Z","caller":"traceutil/trace.go:171","msg":"trace[1476134592] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:391; }","duration":"400.47439ms","start":"2026-02-24T04:35:24.805671Z","end":"2026-02-24T04:35:25.206145Z","steps":["trace[1476134592] 'read index received' (duration: 400.278054ms)","trace[1476134592] 'applied index is now lower than readState.Index' (duration: 195.635µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:35:25.206667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:24.495222Z","time spent":"711.056109ms","remote":"127.0.0.1:50668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:35:25.2064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.707986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:35:25.207197Z","caller":"traceutil/trace.go:171","msg":"trace[1265366198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:377; }","duration":"401.567899ms","start":"2026-02-24T04:35:24.805603Z","end":"2026-02-24T04:35:25.207171Z","steps":["trace[1265366198] 'agreement among raft nodes before linearized reading' (duration: 400.715646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:25.207381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:24.805583Z","time spent":"401.780864ms","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T04:35:25.366292Z","caller":"traceutil/trace.go:171","msg":"trace[962223887] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"107.794835ms","start":"2026-02-24T04:35:25.258468Z","end":"2026-02-24T04:35:25.366262Z","steps":["trace[962223887] 'process raft request' (duration: 63.871627ms)","trace[962223887] 'compare' (duration: 43.773204ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:35:25.368785Z","caller":"traceutil/trace.go:171","msg":"trace[1650559955] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"108.598757ms","start":"2026-02-24T04:35:25.260172Z","end":"2026-02-24T04:35:25.368771Z","steps":["trace[1650559955] 'process raft request' (duration: 108.534365ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:25.368807Z","caller":"traceutil/trace.go:171","msg":"trace[1254373962] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"108.683729ms","start":"2026-02-24T04:35:25.260091Z","end":"2026-02-24T04:35:25.368774Z","steps":["trace[1254373962] 'process raft request' (duration: 108.549425ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:25.369075Z","caller":"traceutil/trace.go:171","msg":"trace[702979129] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"107.743934ms","start":"2026-02-24T04:35:25.261323Z","end":"2026-02-24T04:35:25.369067Z","steps":["trace[702979129] 'process raft request' (duration: 107.409035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:31.120411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:30.705347Z","time spent":"415.061365ms","remote":"127.0.0.1:50218","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-24T04:35:36.078084Z","caller":"traceutil/trace.go:171","msg":"trace[1920095932] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"117.714873ms","start":"2026-02-24T04:35:35.960343Z","end":"2026-02-24T04:35:36.078057Z","steps":["trace[1920095932] 'process raft request' (duration: 117.514248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:39.933734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.28424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-24T04:35:39.933737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.500138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4021"} {"level":"info","ts":"2026-02-24T04:35:39.933848Z","caller":"traceutil/trace.go:171","msg":"trace[1815516106] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:473; }","duration":"428.633052ms","start":"2026-02-24T04:35:39.505189Z","end":"2026-02-24T04:35:39.933822Z","steps":["trace[1815516106] 'range keys from in-memory index tree' (duration: 428.426266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:39.933907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:39.505179Z","time spent":"428.715764ms","remote":"127.0.0.1:50382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4044,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-24T04:35:39.933961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.499273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T04:35:39.934144Z","caller":"traceutil/trace.go:171","msg":"trace[828149232] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:473; }","duration":"254.749229ms","start":"2026-02-24T04:35:39.679366Z","end":"2026-02-24T04:35:39.934115Z","steps":["trace[828149232] 'range keys from in-memory index tree' (duration: 254.347759ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:39.933832Z","caller":"traceutil/trace.go:171","msg":"trace[938124543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:473; }","duration":"253.418874ms","start":"2026-02-24T04:35:39.680391Z","end":"2026-02-24T04:35:39.93381Z","steps":["trace[938124543] 'range keys from in-memory index tree' (duration: 253.185758ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:39.940169Z","caller":"traceutil/trace.go:171","msg":"trace[24988965] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"177.610273ms","start":"2026-02-24T04:35:39.762532Z","end":"2026-02-24T04:35:39.940142Z","steps":["trace[24988965] 'process raft request' (duration: 177.447379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:46.281168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.215814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-24T04:35:46.281278Z","caller":"traceutil/trace.go:171","msg":"trace[278667954] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:494; }","duration":"163.396669ms","start":"2026-02-24T04:35:46.117849Z","end":"2026-02-24T04:35:46.281246Z","steps":["trace[278667954] 'range keys from in-memory index tree' (duration: 163.096881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:46.747855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.246304ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T04:35:46.748052Z","caller":"traceutil/trace.go:171","msg":"trace[602357597] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:515; }","duration":"327.981251ms","start":"2026-02-24T04:35:46.420047Z","end":"2026-02-24T04:35:46.748028Z","steps":["trace[602357597] 'read index received' (duration: 38.091µs)","trace[602357597] 'applied index is now lower than readState.Index' (duration: 327.94061ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:35:46.748242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.174646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:35:46.748347Z","caller":"traceutil/trace.go:171","msg":"trace[1368183877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"328.33016ms","start":"2026-02-24T04:35:46.419989Z","end":"2026-02-24T04:35:46.748319Z","steps":["trace[1368183877] 'agreement among raft nodes before linearized reading' (duration: 328.138344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:46.748276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.359834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xfphm\" ","response":"range_response_count:1 size:16656"} {"level":"warn","ts":"2026-02-24T04:35:46.748426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:46.419962Z","time spent":"328.453183ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T04:35:46.748487Z","caller":"traceutil/trace.go:171","msg":"trace[372336503] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xfphm; range_end:; response_count:1; response_revision:495; }","duration":"117.564899ms","start":"2026-02-24T04:35:46.63085Z","end":"2026-02-24T04:35:46.748415Z","steps":["trace[372336503] 'agreement among raft nodes before linearized reading' (duration: 117.322703ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:47.188573Z","caller":"traceutil/trace.go:171","msg":"trace[1511151206] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"421.343075ms","start":"2026-02-24T04:35:46.767209Z","end":"2026-02-24T04:35:47.188552Z","steps":["trace[1511151206] 'process raft request' (duration: 421.199491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:47.18869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:46.76716Z","time spent":"421.470958ms","remote":"127.0.0.1:50396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:35:48.265692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.94347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-24T04:35:48.265788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.137773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T04:35:48.265804Z","caller":"traceutil/trace.go:171","msg":"trace[1570753150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:496; }","duration":"267.107984ms","start":"2026-02-24T04:35:47.99867Z","end":"2026-02-24T04:35:48.265778Z","steps":["trace[1570753150] 'range keys from in-memory index tree' (duration: 266.857528ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:48.265834Z","caller":"traceutil/trace.go:171","msg":"trace[1144087196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:496; }","duration":"738.224905ms","start":"2026-02-24T04:35:47.527595Z","end":"2026-02-24T04:35:48.26582Z","steps":["trace[1144087196] 'range keys from in-memory index tree' (duration: 737.868636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:48.265819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.368766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-24T04:35:48.265692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.475806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T04:35:48.265873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:47.527555Z","time spent":"738.306558ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T04:35:48.265912Z","caller":"traceutil/trace.go:171","msg":"trace[1403276590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:496; }","duration":"282.51943ms","start":"2026-02-24T04:35:47.983373Z","end":"2026-02-24T04:35:48.265893Z","steps":["trace[1403276590] 'range keys from in-memory index tree' (duration: 282.253133ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:48.265928Z","caller":"traceutil/trace.go:171","msg":"trace[483155717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"845.769224ms","start":"2026-02-24T04:35:47.420133Z","end":"2026-02-24T04:35:48.265902Z","steps":["trace[483155717] 'range keys from in-memory index tree' (duration: 845.399944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:48.265987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:47.420111Z","time spent":"845.859767ms","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:35:48.833546Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527001371285347,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T04:35:48.883284Z","caller":"traceutil/trace.go:171","msg":"trace[1100385200] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"611.858008ms","start":"2026-02-24T04:35:48.271396Z","end":"2026-02-24T04:35:48.883254Z","steps":["trace[1100385200] 'process raft request' (duration: 611.636372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:48.883471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:48.271372Z","time spent":"611.998631ms","remote":"127.0.0.1:50472","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-02-24T04:35:48.885919Z","caller":"traceutil/trace.go:171","msg":"trace[1091169823] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:517; }","duration":"552.723629ms","start":"2026-02-24T04:35:48.333175Z","end":"2026-02-24T04:35:48.885899Z","steps":["trace[1091169823] 'read index received' (duration: 549.82966ms)","trace[1091169823] 'applied index is now lower than readState.Index' (duration: 2.893149ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:35:48.885981Z","caller":"traceutil/trace.go:171","msg":"trace[1687381078] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"613.354408ms","start":"2026-02-24T04:35:48.272589Z","end":"2026-02-24T04:35:48.885943Z","steps":["trace[1687381078] 'process raft request' (duration: 613.137492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:48.88608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.887353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-24T04:35:48.88613Z","caller":"traceutil/trace.go:171","msg":"trace[1895179019] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:499; }","duration":"552.977536ms","start":"2026-02-24T04:35:48.333142Z","end":"2026-02-24T04:35:48.88612Z","steps":["trace[1895179019] 'agreement among raft nodes before linearized reading' (duration: 552.827812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:48.886182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:48.33313Z","time spent":"553.042627ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T04:35:48.886221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:48.272579Z","time spent":"613.529803ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:35:48.887146Z","caller":"traceutil/trace.go:171","msg":"trace[783007263] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"613.530274ms","start":"2026-02-24T04:35:48.27262Z","end":"2026-02-24T04:35:48.88615Z","steps":["trace[783007263] 'process raft request' (duration: 613.231335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:48.887409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:48.272602Z","time spent":"614.719245ms","remote":"127.0.0.1:50472","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-02-24T04:35:51.076171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.318296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T04:35:51.076302Z","caller":"traceutil/trace.go:171","msg":"trace[1846755675] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"177.476401ms","start":"2026-02-24T04:35:50.898798Z","end":"2026-02-24T04:35:51.076275Z","steps":["trace[1846755675] 'range keys from in-memory index tree' (duration: 177.188552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:51.076164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.439065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-24T04:35:51.076363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.389851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T04:35:51.076565Z","caller":"traceutil/trace.go:171","msg":"trace[931562919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:526; }","duration":"186.641318ms","start":"2026-02-24T04:35:50.889905Z","end":"2026-02-24T04:35:51.076547Z","steps":["trace[931562919] 'range keys from in-memory index tree' (duration: 186.287598ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:51.076482Z","caller":"traceutil/trace.go:171","msg":"trace[2030442983] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:526; }","duration":"185.783125ms","start":"2026-02-24T04:35:50.890678Z","end":"2026-02-24T04:35:51.076461Z","steps":["trace[2030442983] 'range keys from in-memory index tree' (duration: 185.372923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:51.07667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.116648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T04:35:51.076989Z","caller":"traceutil/trace.go:171","msg":"trace[1294689768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:526; }","duration":"188.863368ms","start":"2026-02-24T04:35:50.888088Z","end":"2026-02-24T04:35:51.076951Z","steps":["trace[1294689768] 'range keys from in-memory index tree' (duration: 187.953273ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:51.081942Z","caller":"traceutil/trace.go:171","msg":"trace[1334854878] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"170.361477ms","start":"2026-02-24T04:35:50.911562Z","end":"2026-02-24T04:35:51.081923Z","steps":["trace[1334854878] 'process raft request' (duration: 170.212853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:54.033847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.389621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-24T04:35:54.033951Z","caller":"traceutil/trace.go:171","msg":"trace[841498066] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:570; }","duration":"123.576816ms","start":"2026-02-24T04:35:53.910349Z","end":"2026-02-24T04:35:54.033926Z","steps":["trace[841498066] 'range keys from in-memory index tree' (duration: 123.189936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:55.758768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.961796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:35:55.758866Z","caller":"traceutil/trace.go:171","msg":"trace[1553681877] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:592; }","duration":"114.095808ms","start":"2026-02-24T04:35:55.644757Z","end":"2026-02-24T04:35:55.758853Z","steps":["trace[1553681877] 'agreement among raft nodes before linearized reading' (duration: 113.899794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:55.758842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.211741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:35:55.759234Z","caller":"traceutil/trace.go:171","msg":"trace[1520759921] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:592; }","duration":"114.647042ms","start":"2026-02-24T04:35:55.644562Z","end":"2026-02-24T04:35:55.759209Z","steps":["trace[1520759921] 'agreement among raft nodes before linearized reading' (duration: 114.13379ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:59.733382Z","caller":"traceutil/trace.go:171","msg":"trace[1717724128] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"125.508493ms","start":"2026-02-24T04:35:59.607849Z","end":"2026-02-24T04:35:59.733358Z","steps":["trace[1717724128] 'process raft request' (duration: 125.456951ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:59.733706Z","caller":"traceutil/trace.go:171","msg":"trace[2073003741] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"126.662179ms","start":"2026-02-24T04:35:59.60703Z","end":"2026-02-24T04:35:59.733692Z","steps":["trace[2073003741] 'process raft request' (duration: 109.868821ms)","trace[2073003741] 'compare' (duration: 16.296587ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:36:01.273007Z","caller":"traceutil/trace.go:171","msg":"trace[77647476] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"107.804993ms","start":"2026-02-24T04:36:01.165184Z","end":"2026-02-24T04:36:01.272989Z","steps":["trace[77647476] 'process raft request' (duration: 76.261034ms)","trace[77647476] 'compare' (duration: 31.386955ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:36:03.236676Z","caller":"traceutil/trace.go:171","msg":"trace[1763760544] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"402.324155ms","start":"2026-02-24T04:36:02.834321Z","end":"2026-02-24T04:36:03.236645Z","steps":["trace[1763760544] 'process raft request' (duration: 401.634529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:03.236851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:02.834298Z","time spent":"402.454348ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1611,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:36:09.119954Z","caller":"traceutil/trace.go:171","msg":"trace[783915470] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"409.550172ms","start":"2026-02-24T04:36:08.710375Z","end":"2026-02-24T04:36:09.119925Z","steps":["trace[783915470] 'process raft request' (duration: 409.412858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:09.120105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:08.710343Z","time spent":"409.691094ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:36:10.949327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.529235556s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:36:10.949437Z","caller":"traceutil/trace.go:171","msg":"trace[402675730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"1.529367639s","start":"2026-02-24T04:36:09.420048Z","end":"2026-02-24T04:36:10.949415Z","steps":["trace[402675730] 'range keys from in-memory index tree' (duration: 1.529146964s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:10.949482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:09.419996Z","time spent":"1.529477971s","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:36:10.949516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.5017096s","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-02-24T04:36:10.949599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.629162497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-24T04:36:10.949628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.396383434s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T04:36:10.949645Z","caller":"traceutil/trace.go:171","msg":"trace[868707686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:683; }","duration":"1.501927385s","start":"2026-02-24T04:36:09.447693Z","end":"2026-02-24T04:36:10.94962Z","steps":["trace[868707686] 'range keys from in-memory index tree' (duration: 1.501492244s)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:10.949691Z","caller":"traceutil/trace.go:171","msg":"trace[62685093] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:683; }","duration":"1.629309401s","start":"2026-02-24T04:36:09.320357Z","end":"2026-02-24T04:36:10.949667Z","steps":["trace[62685093] 'range keys from in-memory index tree' (duration: 1.628955272s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:10.949729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:09.447673Z","time spent":"1.502044237s","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T04:36:10.94971Z","caller":"traceutil/trace.go:171","msg":"trace[313055183] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:683; }","duration":"1.396524057s","start":"2026-02-24T04:36:09.553163Z","end":"2026-02-24T04:36:10.949687Z","steps":["trace[313055183] 'count revisions from in-memory index tree' (duration: 1.396287512s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:10.949779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:09.55314Z","time spent":"1.396628659s","remote":"127.0.0.1:50268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":79,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:36:10.949812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.632725599s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T04:36:10.94984Z","caller":"traceutil/trace.go:171","msg":"trace[160281164] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:683; }","duration":"1.632787591s","start":"2026-02-24T04:36:09.317043Z","end":"2026-02-24T04:36:10.949831Z","steps":["trace[160281164] 'range keys from in-memory index tree' (duration: 1.632623447s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:10.949865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:09.317024Z","time spent":"1.632835392s","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T04:36:10.949737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:09.320336Z","time spent":"1.629391732s","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T04:36:10.950059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.506805248s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T04:36:10.950086Z","caller":"traceutil/trace.go:171","msg":"trace[1099079678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:683; }","duration":"1.506860358s","start":"2026-02-24T04:36:09.443217Z","end":"2026-02-24T04:36:10.950077Z","steps":["trace[1099079678] 'range keys from in-memory index tree' (duration: 1.506676114s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:10.950123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:09.443184Z","time spent":"1.50693281s","remote":"127.0.0.1:50472","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-02-24T04:36:10.950127Z","caller":"traceutil/trace.go:171","msg":"trace[984264549] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"243.086292ms","start":"2026-02-24T04:36:10.706991Z","end":"2026-02-24T04:36:10.950077Z","steps":["trace[984264549] 'read index received' (duration: 202.995365ms)","trace[984264549] 'applied index is now lower than readState.Index' (duration: 40.089577ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:36:10.950228Z","caller":"traceutil/trace.go:171","msg":"trace[553496861] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"513.357382ms","start":"2026-02-24T04:36:10.436852Z","end":"2026-02-24T04:36:10.95021Z","steps":["trace[553496861] 'process raft request' (duration: 473.098311ms)","trace[553496861] 'compare' (duration: 39.264658ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:36:10.950338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:10.436807Z","time spent":"513.461345ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:36:10.950345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.332938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.141\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T04:36:10.950384Z","caller":"traceutil/trace.go:171","msg":"trace[971083628] range","detail":"{range_begin:/registry/masterleases/199.204.45.141; range_end:; response_count:1; response_revision:684; }","duration":"243.41843ms","start":"2026-02-24T04:36:10.706953Z","end":"2026-02-24T04:36:10.950372Z","steps":["trace[971083628] 'agreement among raft nodes before linearized reading' (duration: 243.240286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:11.460523Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527001371285696,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:36:12.104213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.513446ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-24T04:36:12.10437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:10.95186Z","time spent":"1.152503023s","remote":"127.0.0.1:50218","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-24T04:36:12.178739Z","caller":"traceutil/trace.go:171","msg":"trace[1860161605] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:710; }","duration":"1.218782885s","start":"2026-02-24T04:36:10.959934Z","end":"2026-02-24T04:36:12.178717Z","steps":["trace[1860161605] 'read index received' (duration: 716.489629ms)","trace[1860161605] 'applied index is now lower than readState.Index' (duration: 502.292496ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:36:12.178851Z","caller":"traceutil/trace.go:171","msg":"trace[1964300399] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"1.223476583s","start":"2026-02-24T04:36:10.955367Z","end":"2026-02-24T04:36:12.178844Z","steps":["trace[1964300399] 'process raft request' (duration: 1.223300859s)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:12.178831Z","caller":"traceutil/trace.go:171","msg":"trace[2247452] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"1.224544288s","start":"2026-02-24T04:36:10.954252Z","end":"2026-02-24T04:36:12.178796Z","steps":["trace[2247452] 'process raft request' (duration: 1.224391615s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:12.178954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:10.955358Z","time spent":"1.223520225s","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:36:12.179004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.219060912s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T04:36:12.178998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:10.954242Z","time spent":"1.224665331s","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:36:12.179004Z","caller":"traceutil/trace.go:171","msg":"trace[1842968884] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"1.222355498s","start":"2026-02-24T04:36:10.956615Z","end":"2026-02-24T04:36:12.178971Z","steps":["trace[1842968884] 'process raft request' (duration: 1.222072192s)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:12.17893Z","caller":"traceutil/trace.go:171","msg":"trace[663292990] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"1.224742163s","start":"2026-02-24T04:36:10.954167Z","end":"2026-02-24T04:36:12.17891Z","steps":["trace[663292990] 'process raft request' (duration: 1.224331323s)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:12.179037Z","caller":"traceutil/trace.go:171","msg":"trace[470213253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:688; }","duration":"1.219100683s","start":"2026-02-24T04:36:10.959928Z","end":"2026-02-24T04:36:12.179029Z","steps":["trace[470213253] 'agreement among raft nodes before linearized reading' (duration: 1.219028941s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:12.179155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:10.954145Z","time spent":"1.224950798s","remote":"127.0.0.1:50472","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-02-24T04:36:12.179257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:10.95989Z","time spent":"1.219321448s","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:36:12.179151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:10.956607Z","time spent":"1.222468421s","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:36:12.556938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.925521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:36:12.557082Z","caller":"traceutil/trace.go:171","msg":"trace[1625522188] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:715; }","duration":"378.246567ms","start":"2026-02-24T04:36:12.178811Z","end":"2026-02-24T04:36:12.557058Z","steps":["trace[1625522188] 'read index received' (duration: 83.065861ms)","trace[1625522188] 'applied index is now lower than readState.Index' (duration: 295.178816ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:36:12.557224Z","caller":"traceutil/trace.go:171","msg":"trace[1773650124] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"451.283617ms","start":"2026-02-24T04:36:12.10592Z","end":"2026-02-24T04:36:12.557204Z","steps":["trace[1773650124] 'process raft request' (duration: 155.957907ms)","trace[1773650124] 'compare' (duration: 294.691755ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:36:12.557423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.336534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:36:12.557508Z","caller":"traceutil/trace.go:171","msg":"trace[1950699701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"376.434526ms","start":"2026-02-24T04:36:12.181055Z","end":"2026-02-24T04:36:12.557489Z","steps":["trace[1950699701] 'agreement among raft nodes before linearized reading' (duration: 376.260072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:12.557524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.432256743s","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-02-24T04:36:12.557552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:12.181015Z","time spent":"376.529919ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T04:36:12.557589Z","caller":"traceutil/trace.go:171","msg":"trace[891230797] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:689; }","duration":"1.432375625s","start":"2026-02-24T04:36:11.1252Z","end":"2026-02-24T04:36:12.557575Z","steps":["trace[891230797] 'agreement among raft nodes before linearized reading' (duration: 1.4321415s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:12.557638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:11.125173Z","time spent":"1.432454367s","remote":"127.0.0.1:50472","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-02-24T04:36:12.558103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:12.105898Z","time spent":"451.40728ms","remote":"127.0.0.1:50218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:36:13.041917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.827601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:36:13.042043Z","caller":"traceutil/trace.go:171","msg":"trace[1176340815] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"479.604542ms","start":"2026-02-24T04:36:12.562421Z","end":"2026-02-24T04:36:13.042026Z","steps":["trace[1176340815] 'read index received' (duration: 228.553306ms)","trace[1176340815] 'applied index is now lower than readState.Index' (duration: 251.022265ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:36:13.042133Z","caller":"traceutil/trace.go:171","msg":"trace[2131547748] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"480.466781ms","start":"2026-02-24T04:36:12.561656Z","end":"2026-02-24T04:36:13.042123Z","steps":["trace[2131547748] 'process raft request' (duration: 229.343944ms)","trace[2131547748] 'compare' (duration: 250.582935ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:36:13.04219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:12.561625Z","time spent":"480.533353ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:36:13.042273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.414722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:36:13.042401Z","caller":"traceutil/trace.go:171","msg":"trace[1668323227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:690; }","duration":"474.547464ms","start":"2026-02-24T04:36:12.567794Z","end":"2026-02-24T04:36:13.042342Z","steps":["trace[1668323227] 'agreement among raft nodes before linearized reading' (duration: 474.408181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:13.042445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:12.567776Z","time spent":"474.661037ms","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:36:13.042608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.177725ms","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-02-24T04:36:13.042646Z","caller":"traceutil/trace.go:171","msg":"trace[2003461970] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:690; }","duration":"480.236666ms","start":"2026-02-24T04:36:12.562398Z","end":"2026-02-24T04:36:13.042634Z","steps":["trace[2003461970] 'agreement among raft nodes before linearized reading' (duration: 480.085832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:13.042678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:12.562386Z","time spent":"480.284987ms","remote":"127.0.0.1:50218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-02-24T04:36:14.168413Z","caller":"traceutil/trace.go:171","msg":"trace[1914043736] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"488.1649ms","start":"2026-02-24T04:36:13.680206Z","end":"2026-02-24T04:36:14.168371Z","steps":["trace[1914043736] 'process raft request' (duration: 487.933104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:14.168664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:13.680182Z","time spent":"488.333194ms","remote":"127.0.0.1:50382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4728,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:36:14.171643Z","caller":"traceutil/trace.go:171","msg":"trace[905713846] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"322.166631ms","start":"2026-02-24T04:36:13.849458Z","end":"2026-02-24T04:36:14.171625Z","steps":["trace[905713846] 'process raft request' (duration: 321.941246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:14.171736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:13.849415Z","time spent":"322.276443ms","remote":"127.0.0.1:50472","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-02-24T04:36:14.720323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.106314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T04:36:14.720342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.192663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T04:36:14.720442Z","caller":"traceutil/trace.go:171","msg":"trace[125492683] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:696; }","duration":"233.342987ms","start":"2026-02-24T04:36:14.487077Z","end":"2026-02-24T04:36:14.72042Z","steps":["trace[125492683] 'count revisions from in-memory index tree' (duration: 233.128822ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:14.720437Z","caller":"traceutil/trace.go:171","msg":"trace[105684308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:696; }","duration":"301.274938ms","start":"2026-02-24T04:36:14.419131Z","end":"2026-02-24T04:36:14.720406Z","steps":["trace[105684308] 'range keys from in-memory index tree' (duration: 300.94938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:14.720537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:14.419106Z","time spent":"301.416511ms","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T04:36:15.24999Z","caller":"traceutil/trace.go:171","msg":"trace[1192653983] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"198.274885ms","start":"2026-02-24T04:36:15.051695Z","end":"2026-02-24T04:36:15.24997Z","steps":["trace[1192653983] 'process raft request' (duration: 198.143652ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:18.346213Z","caller":"traceutil/trace.go:171","msg":"trace[1288561771] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"106.35325ms","start":"2026-02-24T04:36:18.239805Z","end":"2026-02-24T04:36:18.346159Z","steps":["trace[1288561771] 'process raft request' (duration: 102.102932ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:18.351349Z","caller":"traceutil/trace.go:171","msg":"trace[1925549002] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"110.549386ms","start":"2026-02-24T04:36:18.240766Z","end":"2026-02-24T04:36:18.351316Z","steps":["trace[1925549002] 'process raft request' (duration: 110.252129ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:18.351901Z","caller":"traceutil/trace.go:171","msg":"trace[1862292936] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"111.56307ms","start":"2026-02-24T04:36:18.240317Z","end":"2026-02-24T04:36:18.35188Z","steps":["trace[1862292936] 'process raft request' (duration: 105.135011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:36.866155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.623927ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T04:36:36.86623Z","caller":"traceutil/trace.go:171","msg":"trace[1606445695] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:790; }","duration":"609.090785ms","start":"2026-02-24T04:36:36.257126Z","end":"2026-02-24T04:36:36.866217Z","steps":["trace[1606445695] 'read index received' (duration: 104.772µs)","trace[1606445695] 'applied index is now lower than readState.Index' (duration: 608.984683ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:36:36.866624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.671159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-24T04:36:36.86662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.464894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T04:36:36.866606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.398387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:36:36.866646Z","caller":"traceutil/trace.go:171","msg":"trace[1751423892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:758; }","duration":"375.73923ms","start":"2026-02-24T04:36:36.490901Z","end":"2026-02-24T04:36:36.86664Z","steps":["trace[1751423892] 'agreement among raft nodes before linearized reading' (duration: 375.634608ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:36.866654Z","caller":"traceutil/trace.go:171","msg":"trace[771532962] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:758; }","duration":"609.566336ms","start":"2026-02-24T04:36:36.257076Z","end":"2026-02-24T04:36:36.866642Z","steps":["trace[771532962] 'agreement among raft nodes before linearized reading' (duration: 609.485164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:36.866665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:36.490874Z","time spent":"375.787631ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T04:36:36.866672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:36.257056Z","time spent":"609.610608ms","remote":"127.0.0.1:50368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-02-24T04:36:36.866663Z","caller":"traceutil/trace.go:171","msg":"trace[104267552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:758; }","duration":"447.483639ms","start":"2026-02-24T04:36:36.419166Z","end":"2026-02-24T04:36:36.86665Z","steps":["trace[104267552] 'agreement among raft nodes before linearized reading' (duration: 447.405558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:36.866691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:36.419156Z","time spent":"447.53126ms","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:36:36.866791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.72096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T04:36:36.866807Z","caller":"traceutil/trace.go:171","msg":"trace[1431332679] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:758; }","duration":"500.76798ms","start":"2026-02-24T04:36:36.366035Z","end":"2026-02-24T04:36:36.866803Z","steps":["trace[1431332679] 'agreement among raft nodes before linearized reading' (duration: 500.494644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:36.866821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:36.365993Z","time spent":"500.824831ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T04:36:36.866827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.91819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T04:36:36.866917Z","caller":"traceutil/trace.go:171","msg":"trace[2065105991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:758; }","duration":"413.055543ms","start":"2026-02-24T04:36:36.453841Z","end":"2026-02-24T04:36:36.866896Z","steps":["trace[2065105991] 'agreement among raft nodes before linearized reading' (duration: 412.718735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:36.866954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.314921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-24T04:36:36.866965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:36.453818Z","time spent":"413.137884ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T04:36:36.866981Z","caller":"traceutil/trace.go:171","msg":"trace[1408363835] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:758; }","duration":"369.360702ms","start":"2026-02-24T04:36:36.497612Z","end":"2026-02-24T04:36:36.866972Z","steps":["trace[1408363835] 'agreement among raft nodes before linearized reading' (duration: 369.047805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:36.866999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:36.4976Z","time spent":"369.395433ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T04:36:37.311738Z","caller":"traceutil/trace.go:171","msg":"trace[1833739418] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"442.156315ms","start":"2026-02-24T04:36:36.869556Z","end":"2026-02-24T04:36:37.311712Z","steps":["trace[1833739418] 'process raft request' (duration: 441.992202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:37.311892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:36.869547Z","time spent":"442.266599ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:36:37.474281Z","caller":"traceutil/trace.go:171","msg":"trace[128552368] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:792; }","duration":"112.678115ms","start":"2026-02-24T04:36:37.36158Z","end":"2026-02-24T04:36:37.474258Z","steps":["trace[128552368] 'read index received' (duration: 66.371805ms)","trace[128552368] 'applied index is now lower than readState.Index' (duration: 46.30511ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:36:37.474416Z","caller":"traceutil/trace.go:171","msg":"trace[725429462] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"603.366243ms","start":"2026-02-24T04:36:36.870936Z","end":"2026-02-24T04:36:37.474302Z","steps":["trace[725429462] 'process raft request' (duration: 557.139974ms)","trace[725429462] 'compare' (duration: 45.780329ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:36:37.474497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.910121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T04:36:37.474488Z","caller":"traceutil/trace.go:171","msg":"trace[1551040905] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"592.39325ms","start":"2026-02-24T04:36:36.88206Z","end":"2026-02-24T04:36:37.474453Z","steps":["trace[1551040905] 'process raft request' (duration: 592.159755ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:37.47454Z","caller":"traceutil/trace.go:171","msg":"trace[1893614548] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:762; }","duration":"112.968133ms","start":"2026-02-24T04:36:37.361562Z","end":"2026-02-24T04:36:37.47453Z","steps":["trace[1893614548] 'agreement among raft nodes before linearized reading' (duration: 112.781828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:37.474631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:36.870907Z","time spent":"603.587389ms","remote":"127.0.0.1:50472","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-02-24T04:36:37.474649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:36.88205Z","time spent":"592.510742ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:36:37.474408Z","caller":"traceutil/trace.go:171","msg":"trace[2077651287] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"603.364064ms","start":"2026-02-24T04:36:36.871012Z","end":"2026-02-24T04:36:37.474376Z","steps":["trace[2077651287] 'process raft request' (duration: 603.153199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:37.47494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:36.871008Z","time spent":"603.774194ms","remote":"127.0.0.1:50472","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-02-24T04:36:37.723405Z","caller":"traceutil/trace.go:171","msg":"trace[1073533607] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"244.631247ms","start":"2026-02-24T04:36:37.478733Z","end":"2026-02-24T04:36:37.723364Z","steps":["trace[1073533607] 'process raft request' (duration: 176.891441ms)","trace[1073533607] 'compare' (duration: 67.47916ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:36:37.723632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.570111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T04:36:37.723775Z","caller":"traceutil/trace.go:171","msg":"trace[1754315137] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:763; }","duration":"140.768665ms","start":"2026-02-24T04:36:37.582985Z","end":"2026-02-24T04:36:37.723754Z","steps":["trace[1754315137] 'agreement among raft nodes before linearized reading' (duration: 140.458268ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:37.723816Z","caller":"traceutil/trace.go:171","msg":"trace[928379515] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"140.282934ms","start":"2026-02-24T04:36:37.583031Z","end":"2026-02-24T04:36:37.723314Z","steps":["trace[928379515] 'read index received' (duration: 72.500457ms)","trace[928379515] 'applied index is now lower than readState.Index' (duration: 67.781557ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:36:39.63939Z","caller":"traceutil/trace.go:171","msg":"trace[26092571] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"160.756468ms","start":"2026-02-24T04:36:39.47861Z","end":"2026-02-24T04:36:39.639366Z","steps":["trace[26092571] 'read index received' (duration: 160.633705ms)","trace[26092571] 'applied index is now lower than readState.Index' (duration: 121.453µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:36:39.639443Z","caller":"traceutil/trace.go:171","msg":"trace[267703390] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"319.277724ms","start":"2026-02-24T04:36:39.320134Z","end":"2026-02-24T04:36:39.639412Z","steps":["trace[267703390] 'process raft request' (duration: 319.077749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:39.639653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.954232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T04:36:39.639692Z","caller":"traceutil/trace.go:171","msg":"trace[649684706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:764; }","duration":"161.060395ms","start":"2026-02-24T04:36:39.478621Z","end":"2026-02-24T04:36:39.639682Z","steps":["trace[649684706] 'agreement among raft nodes before linearized reading' (duration: 160.897251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:39.639698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.072405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T04:36:39.639758Z","caller":"traceutil/trace.go:171","msg":"trace[699829594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:764; }","duration":"161.180968ms","start":"2026-02-24T04:36:39.47856Z","end":"2026-02-24T04:36:39.639741Z","steps":["trace[699829594] 'agreement among raft nodes before linearized reading' (duration: 160.914311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:39.639932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.803659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T04:36:39.640003Z","caller":"traceutil/trace.go:171","msg":"trace[1405724455] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:764; }","duration":"160.87383ms","start":"2026-02-24T04:36:39.479117Z","end":"2026-02-24T04:36:39.639991Z","steps":["trace[1405724455] 'agreement among raft nodes before linearized reading' (duration: 160.622054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:39.640125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.558289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T04:36:39.640281Z","caller":"traceutil/trace.go:171","msg":"trace[254291162] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:764; }","duration":"130.879876ms","start":"2026-02-24T04:36:39.509378Z","end":"2026-02-24T04:36:39.640258Z","steps":["trace[254291162] 'agreement among raft nodes before linearized reading' (duration: 130.515838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:39.640305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:39.320123Z","time spent":"319.392256ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:36:40.323417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.550544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:36:40.323548Z","caller":"traceutil/trace.go:171","msg":"trace[1504157050] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"677.095368ms","start":"2026-02-24T04:36:39.646428Z","end":"2026-02-24T04:36:40.323523Z","steps":["trace[1504157050] 'process raft request' (duration: 317.338489ms)","trace[1504157050] 'compare' (duration: 359.277838ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:36:40.323642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:39.646406Z","time spent":"677.19338ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:36:40.325268Z","caller":"traceutil/trace.go:171","msg":"trace[1894131359] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"678.706635ms","start":"2026-02-24T04:36:39.646543Z","end":"2026-02-24T04:36:40.325249Z","steps":["trace[1894131359] 'process raft request' (duration: 678.543071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:40.325372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:39.646515Z","time spent":"678.803378ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:36:40.325358Z","caller":"traceutil/trace.go:171","msg":"trace[1486093884] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"678.653164ms","start":"2026-02-24T04:36:39.646665Z","end":"2026-02-24T04:36:40.325318Z","steps":["trace[1486093884] 'process raft request' (duration: 678.51392ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:40.325241Z","caller":"traceutil/trace.go:171","msg":"trace[1975075243] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:797; }","duration":"597.780733ms","start":"2026-02-24T04:36:39.727436Z","end":"2026-02-24T04:36:40.325217Z","steps":["trace[1975075243] 'read index received' (duration: 236.310695ms)","trace[1975075243] 'applied index is now lower than readState.Index' (duration: 361.469008ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:36:40.325441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.985458ms","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-02-24T04:36:40.325486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:39.646637Z","time spent":"678.791637ms","remote":"127.0.0.1:50472","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-02-24T04:36:40.325518Z","caller":"traceutil/trace.go:171","msg":"trace[469280775] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:767; }","duration":"598.149012ms","start":"2026-02-24T04:36:39.727359Z","end":"2026-02-24T04:36:40.325508Z","steps":["trace[469280775] 'agreement among raft nodes before linearized reading' (duration: 597.950998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:40.325604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:39.727337Z","time spent":"598.255024ms","remote":"127.0.0.1:50472","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-02-24T04:36:40.789656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.567331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:36:40.789799Z","caller":"traceutil/trace.go:171","msg":"trace[1371496930] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:800; }","duration":"354.5723ms","start":"2026-02-24T04:36:40.435186Z","end":"2026-02-24T04:36:40.789759Z","steps":["trace[1371496930] 'read index received' (duration: 200.788953ms)","trace[1371496930] 'applied index is now lower than readState.Index' (duration: 153.781837ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:36:40.78981Z","caller":"traceutil/trace.go:171","msg":"trace[1057680450] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"459.503067ms","start":"2026-02-24T04:36:40.330271Z","end":"2026-02-24T04:36:40.789774Z","steps":["trace[1057680450] 'process raft request' (duration: 305.760252ms)","trace[1057680450] 'compare' (duration: 152.64725ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:36:40.790082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:40.330244Z","time spent":"459.804284ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:36:40.789904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.724303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:36:40.790167Z","caller":"traceutil/trace.go:171","msg":"trace[1665544356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:768; }","duration":"355.01772ms","start":"2026-02-24T04:36:40.435134Z","end":"2026-02-24T04:36:40.790151Z","steps":["trace[1665544356] 'agreement among raft nodes before linearized reading' (duration: 354.721363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:40.790264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:40.435113Z","time spent":"355.122513ms","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:36:41.292685Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527001371285938,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T04:36:41.406459Z","caller":"traceutil/trace.go:171","msg":"trace[1394956286] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"614.42965ms","start":"2026-02-24T04:36:40.792006Z","end":"2026-02-24T04:36:41.406435Z","steps":["trace[1394956286] 'read index received' (duration: 614.276616ms)","trace[1394956286] 'applied index is now lower than readState.Index' (duration: 151.794µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:36:41.406486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:40.791534Z","time spent":"614.944382ms","remote":"127.0.0.1:50218","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-24T04:36:41.406632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.591348ms","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-02-24T04:36:41.40667Z","caller":"traceutil/trace.go:171","msg":"trace[1129871128] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:768; }","duration":"495.08506ms","start":"2026-02-24T04:36:40.911571Z","end":"2026-02-24T04:36:41.406656Z","steps":["trace[1129871128] 'agreement among raft nodes before linearized reading' (duration: 494.995948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:41.40667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.643575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:36:41.406756Z","caller":"traceutil/trace.go:171","msg":"trace[468424717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:768; }","duration":"614.773728ms","start":"2026-02-24T04:36:40.791963Z","end":"2026-02-24T04:36:41.406736Z","steps":["trace[468424717] 'agreement among raft nodes before linearized reading' (duration: 614.608854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:41.406709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:40.911551Z","time spent":"495.145472ms","remote":"127.0.0.1:50668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:36:41.406863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:40.791947Z","time spent":"614.905591ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T04:36:52.619134Z","caller":"traceutil/trace.go:171","msg":"trace[155472181] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"242.645122ms","start":"2026-02-24T04:36:52.37647Z","end":"2026-02-24T04:36:52.619115Z","steps":["trace[155472181] 'process raft request' (duration: 242.481858ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:52.620232Z","caller":"traceutil/trace.go:171","msg":"trace[271034160] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"243.610564ms","start":"2026-02-24T04:36:52.376612Z","end":"2026-02-24T04:36:52.620223Z","steps":["trace[271034160] 'process raft request' (duration: 243.530712ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:52.620386Z","caller":"traceutil/trace.go:171","msg":"trace[208217804] linearizableReadLoop","detail":"{readStateIndex:892; appliedIndex:890; }","duration":"202.122664ms","start":"2026-02-24T04:36:52.418241Z","end":"2026-02-24T04:36:52.620364Z","steps":["trace[208217804] 'read index received' (duration: 201.372857ms)","trace[208217804] 'applied index is now lower than readState.Index' (duration: 747.627µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:36:52.62052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.269548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:36:52.620538Z","caller":"traceutil/trace.go:171","msg":"trace[587300109] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"242.16092ms","start":"2026-02-24T04:36:52.378367Z","end":"2026-02-24T04:36:52.620528Z","steps":["trace[587300109] 'process raft request' (duration: 241.822172ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:52.620543Z","caller":"traceutil/trace.go:171","msg":"trace[611692678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:853; }","duration":"202.327749ms","start":"2026-02-24T04:36:52.418207Z","end":"2026-02-24T04:36:52.620535Z","steps":["trace[611692678] 'agreement among raft nodes before linearized reading' (duration: 202.231947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:54.311944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527001371286138,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T04:36:54.554013Z","caller":"traceutil/trace.go:171","msg":"trace[1350225117] linearizableReadLoop","detail":"{readStateIndex:894; appliedIndex:893; }","duration":"742.603683ms","start":"2026-02-24T04:36:53.811382Z","end":"2026-02-24T04:36:54.553986Z","steps":["trace[1350225117] 'read index received' (duration: 742.521251ms)","trace[1350225117] 'applied index is now lower than readState.Index' (duration: 81.612µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:36:54.554068Z","caller":"traceutil/trace.go:171","msg":"trace[1684470952] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"852.267579ms","start":"2026-02-24T04:36:53.701776Z","end":"2026-02-24T04:36:54.554044Z","steps":["trace[1684470952] 'process raft request' (duration: 852.117676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:54.554135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.04425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:36:54.554167Z","caller":"traceutil/trace.go:171","msg":"trace[1929109987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:855; }","duration":"137.302666ms","start":"2026-02-24T04:36:54.416855Z","end":"2026-02-24T04:36:54.554158Z","steps":["trace[1929109987] 'agreement among raft nodes before linearized reading' (duration: 137.235944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:54.554194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:53.701756Z","time spent":"852.377842ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:36:54.554177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.769116ms","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-02-24T04:36:54.554282Z","caller":"traceutil/trace.go:171","msg":"trace[73808486] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:855; }","duration":"742.93399ms","start":"2026-02-24T04:36:53.811323Z","end":"2026-02-24T04:36:54.554257Z","steps":["trace[73808486] 'agreement among raft nodes before linearized reading' (duration: 742.752496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:54.55434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:53.811298Z","time spent":"743.031393ms","remote":"127.0.0.1:50504","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":"warn","ts":"2026-02-24T04:36:55.286117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.966385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T04:36:55.28614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.754461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T04:36:55.286183Z","caller":"traceutil/trace.go:171","msg":"trace[216742468] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:855; }","duration":"456.073608ms","start":"2026-02-24T04:36:54.830094Z","end":"2026-02-24T04:36:55.286167Z","steps":["trace[216742468] 'count revisions from in-memory index tree' (duration: 455.899864ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:55.286222Z","caller":"traceutil/trace.go:171","msg":"trace[1655095392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:855; }","duration":"663.870183ms","start":"2026-02-24T04:36:54.622322Z","end":"2026-02-24T04:36:55.286192Z","steps":["trace[1655095392] 'range keys from in-memory index tree' (duration: 663.593347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:55.28623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:54.830078Z","time spent":"456.143089ms","remote":"127.0.0.1:50570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:36:55.286251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:54.622276Z","time spent":"663.969076ms","remote":"127.0.0.1:50472","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-02-24T04:36:55.286413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.016763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T04:36:55.286434Z","caller":"traceutil/trace.go:171","msg":"trace[604503713] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:855; }","duration":"663.071795ms","start":"2026-02-24T04:36:54.623354Z","end":"2026-02-24T04:36:55.286426Z","steps":["trace[604503713] 'range keys from in-memory index tree' (duration: 662.924222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:55.28645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:54.623334Z","time spent":"663.112695ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T04:36:55.286554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.824969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T04:36:55.28657Z","caller":"traceutil/trace.go:171","msg":"trace[61864758] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:855; }","duration":"662.85798ms","start":"2026-02-24T04:36:54.623706Z","end":"2026-02-24T04:36:55.286564Z","steps":["trace[61864758] 'range keys from in-memory index tree' (duration: 662.769528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:55.286588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:54.623699Z","time spent":"662.885571ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T04:36:55.286668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.804725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T04:36:55.286682Z","caller":"traceutil/trace.go:171","msg":"trace[181590340] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:855; }","duration":"434.833597ms","start":"2026-02-24T04:36:54.851844Z","end":"2026-02-24T04:36:55.286677Z","steps":["trace[181590340] 'range keys from in-memory index tree' (duration: 434.749645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:55.286698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:54.851836Z","time spent":"434.857397ms","remote":"127.0.0.1:50472","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-02-24T04:36:55.286852Z","caller":"traceutil/trace.go:171","msg":"trace[737125967] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"302.393453ms","start":"2026-02-24T04:36:54.984448Z","end":"2026-02-24T04:36:55.286841Z","steps":["trace[737125967] 'process raft request' (duration: 301.071393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:55.286909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:54.984429Z","time spent":"302.439655ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:37:07.588124Z","caller":"traceutil/trace.go:171","msg":"trace[1875924385] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"239.847873ms","start":"2026-02-24T04:37:07.348257Z","end":"2026-02-24T04:37:07.588105Z","steps":["trace[1875924385] 'process raft request' (duration: 239.679309ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:07.676114Z","caller":"traceutil/trace.go:171","msg":"trace[2019391297] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:931; }","duration":"256.974042ms","start":"2026-02-24T04:37:07.419128Z","end":"2026-02-24T04:37:07.676102Z","steps":["trace[2019391297] 'read index received' (duration: 168.88942ms)","trace[2019391297] 'applied index is now lower than readState.Index' (duration: 88.084072ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:07.676178Z","caller":"traceutil/trace.go:171","msg":"trace[2075060910] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"327.879795ms","start":"2026-02-24T04:37:07.348292Z","end":"2026-02-24T04:37:07.676172Z","steps":["trace[2075060910] 'process raft request' (duration: 327.750202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:07.676234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:07.348274Z","time spent":"327.914545ms","remote":"127.0.0.1:50472","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-02-24T04:37:07.6763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.181937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:37:07.676321Z","caller":"traceutil/trace.go:171","msg":"trace[1496983101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:891; }","duration":"257.227978ms","start":"2026-02-24T04:37:07.419087Z","end":"2026-02-24T04:37:07.676315Z","steps":["trace[1496983101] 'agreement among raft nodes before linearized reading' (duration: 257.198467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:07.676447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.865242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T04:37:07.676465Z","caller":"traceutil/trace.go:171","msg":"trace[1208361708] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:891; }","duration":"205.909274ms","start":"2026-02-24T04:37:07.470551Z","end":"2026-02-24T04:37:07.676461Z","steps":["trace[1208361708] 'agreement among raft nodes before linearized reading' (duration: 205.848872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:07.676528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.375583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:37:07.676546Z","caller":"traceutil/trace.go:171","msg":"trace[313184184] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:891; }","duration":"102.417033ms","start":"2026-02-24T04:37:07.574124Z","end":"2026-02-24T04:37:07.676541Z","steps":["trace[313184184] 'agreement among raft nodes before linearized reading' (duration: 102.390963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:07.676729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.682955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T04:37:07.676808Z","caller":"traceutil/trace.go:171","msg":"trace[1587633184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:891; }","duration":"163.805058ms","start":"2026-02-24T04:37:07.512977Z","end":"2026-02-24T04:37:07.676782Z","steps":["trace[1587633184] 'agreement among raft nodes before linearized reading' (duration: 163.641694ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:10.929838Z","caller":"traceutil/trace.go:171","msg":"trace[1199049361] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:951; }","duration":"154.919047ms","start":"2026-02-24T04:37:10.774893Z","end":"2026-02-24T04:37:10.929812Z","steps":["trace[1199049361] 'read index received' (duration: 97.259851ms)","trace[1199049361] 'applied index is now lower than readState.Index' (duration: 57.658046ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:10.929918Z","caller":"traceutil/trace.go:171","msg":"trace[2020332737] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"165.620503ms","start":"2026-02-24T04:37:10.764262Z","end":"2026-02-24T04:37:10.929882Z","steps":["trace[2020332737] 'process raft request' (duration: 107.895555ms)","trace[2020332737] 'compare' (duration: 57.40663ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:10.929976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.05938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-24T04:37:10.93005Z","caller":"traceutil/trace.go:171","msg":"trace[401484575] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:909; }","duration":"155.176972ms","start":"2026-02-24T04:37:10.774857Z","end":"2026-02-24T04:37:10.930034Z","steps":["trace[401484575] 'agreement among raft nodes before linearized reading' (duration: 155.049279ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:11.893693Z","caller":"traceutil/trace.go:171","msg":"trace[38152684] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"142.526922ms","start":"2026-02-24T04:37:11.751144Z","end":"2026-02-24T04:37:11.893671Z","steps":["trace[38152684] 'process raft request' (duration: 142.433139ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:11.893748Z","caller":"traceutil/trace.go:171","msg":"trace[1320208999] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"143.054114ms","start":"2026-02-24T04:37:11.750639Z","end":"2026-02-24T04:37:11.893693Z","steps":["trace[1320208999] 'process raft request' (duration: 142.002689ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:11.89383Z","caller":"traceutil/trace.go:171","msg":"trace[1061363139] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"141.361024ms","start":"2026-02-24T04:37:11.752438Z","end":"2026-02-24T04:37:11.893799Z","steps":["trace[1061363139] 'process raft request' (duration: 141.182999ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:13.452027Z","caller":"traceutil/trace.go:171","msg":"trace[635940269] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"396.545714ms","start":"2026-02-24T04:37:13.055455Z","end":"2026-02-24T04:37:13.452001Z","steps":["trace[635940269] 'process raft request' (duration: 396.286127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:13.452158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:13.05543Z","time spent":"396.661196ms","remote":"127.0.0.1:50396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11176,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:37:13.670478Z","caller":"traceutil/trace.go:171","msg":"trace[822249189] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"213.771241ms","start":"2026-02-24T04:37:13.456674Z","end":"2026-02-24T04:37:13.670446Z","steps":["trace[822249189] 'process raft request' (duration: 198.851635ms)","trace[822249189] 'compare' (duration: 14.380653ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:13.751607Z","caller":"traceutil/trace.go:171","msg":"trace[334408854] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:966; }","duration":"130.166186ms","start":"2026-02-24T04:37:13.621398Z","end":"2026-02-24T04:37:13.751564Z","steps":["trace[334408854] 'read index received' (duration: 34.173445ms)","trace[334408854] 'applied index is now lower than readState.Index' (duration: 95.991361ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:13.75186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.447072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T04:37:13.751898Z","caller":"traceutil/trace.go:171","msg":"trace[1322317662] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:926; }","duration":"130.539875ms","start":"2026-02-24T04:37:13.621344Z","end":"2026-02-24T04:37:13.751884Z","steps":["trace[1322317662] 'agreement among raft nodes before linearized reading' (duration: 130.34386ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:13.751951Z","caller":"traceutil/trace.go:171","msg":"trace[973388297] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"294.60563ms","start":"2026-02-24T04:37:13.45731Z","end":"2026-02-24T04:37:13.751915Z","steps":["trace[973388297] 'process raft request' (duration: 293.856122ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:13.752484Z","caller":"traceutil/trace.go:171","msg":"trace[236198675] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"289.157821ms","start":"2026-02-24T04:37:13.463315Z","end":"2026-02-24T04:37:13.752473Z","steps":["trace[236198675] 'process raft request' (duration: 288.068405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:14.145036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.82168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:37:14.145165Z","caller":"traceutil/trace.go:171","msg":"trace[202875291] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:969; }","duration":"306.763051ms","start":"2026-02-24T04:37:13.83838Z","end":"2026-02-24T04:37:14.145144Z","steps":["trace[202875291] 'read index received' (duration: 59.782µs)","trace[202875291] 'applied index is now lower than readState.Index' (duration: 306.701999ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:14.145271Z","caller":"traceutil/trace.go:171","msg":"trace[1253611458] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"382.459927ms","start":"2026-02-24T04:37:13.762775Z","end":"2026-02-24T04:37:14.145235Z","steps":["trace[1253611458] 'process raft request' (duration: 47.33592ms)","trace[1253611458] 'compare' (duration: 334.538693ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:14.145385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.013187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T04:37:14.145424Z","caller":"traceutil/trace.go:171","msg":"trace[398835362] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:927; }","duration":"307.099079ms","start":"2026-02-24T04:37:13.838315Z","end":"2026-02-24T04:37:14.145414Z","steps":["trace[398835362] 'agreement among raft nodes before linearized reading' (duration: 306.946695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:14.145466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:13.838288Z","time spent":"307.170061ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T04:37:14.145474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:13.762752Z","time spent":"382.60694ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:37:14.145606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.83587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T04:37:14.14569Z","caller":"traceutil/trace.go:171","msg":"trace[1260670744] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:927; }","duration":"246.981134ms","start":"2026-02-24T04:37:13.89869Z","end":"2026-02-24T04:37:14.145672Z","steps":["trace[1260670744] 'agreement among raft nodes before linearized reading' (duration: 246.749248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:14.145869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.492082ms","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-02-24T04:37:14.145949Z","caller":"traceutil/trace.go:171","msg":"trace[276400492] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:927; }","duration":"215.696467ms","start":"2026-02-24T04:37:13.93024Z","end":"2026-02-24T04:37:14.145936Z","steps":["trace[276400492] 'agreement among raft nodes before linearized reading' (duration: 215.40035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:14.145955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.075196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T04:37:14.145992Z","caller":"traceutil/trace.go:171","msg":"trace[427289187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:927; }","duration":"247.129407ms","start":"2026-02-24T04:37:13.898853Z","end":"2026-02-24T04:37:14.145983Z","steps":["trace[427289187] 'agreement among raft nodes before linearized reading' (duration: 247.036135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:16.409825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.763763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:37:16.409903Z","caller":"traceutil/trace.go:171","msg":"trace[587209125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:930; }","duration":"990.887917ms","start":"2026-02-24T04:37:15.418997Z","end":"2026-02-24T04:37:16.409885Z","steps":["trace[587209125] 'range keys from in-memory index tree' (duration: 990.678661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:16.40994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:15.418979Z","time spent":"990.953388ms","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:37:16.410098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.371719783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T04:37:16.410128Z","caller":"traceutil/trace.go:171","msg":"trace[1812471031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:930; }","duration":"1.371770234s","start":"2026-02-24T04:37:15.038348Z","end":"2026-02-24T04:37:16.410118Z","steps":["trace[1812471031] 'range keys from in-memory index tree' (duration: 1.371605401s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:16.410147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:15.038335Z","time spent":"1.371807376s","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T04:37:16.6585Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527001371286324,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:37:17.158867Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527001371286324,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:37:17.276464Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.285850186s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-24T04:37:17.465937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.475265686s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T04:37:17.467609Z","caller":"traceutil/trace.go:171","msg":"trace[1524775916] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"1.532331858s","start":"2026-02-24T04:37:15.93526Z","end":"2026-02-24T04:37:17.467592Z","steps":["trace[1524775916] 'process raft request' (duration: 1.530808002s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:17.467703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:15.93523Z","time spent":"1.53242203s","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:37:17.9705Z","caller":"traceutil/trace.go:171","msg":"trace[684071867] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:973; }","duration":"1.812938705s","start":"2026-02-24T04:37:16.157531Z","end":"2026-02-24T04:37:17.97047Z","steps":["trace[684071867] 'read index received' (duration: 1.119094327s)","trace[684071867] 'applied index is now lower than readState.Index' (duration: 693.843118ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:17.970571Z","caller":"traceutil/trace.go:171","msg":"trace[6849605] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"1.934963967s","start":"2026-02-24T04:37:16.035586Z","end":"2026-02-24T04:37:17.97055Z","steps":["trace[6849605] 'process raft request' (duration: 1.934459744s)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:17.97064Z","caller":"traceutil/trace.go:171","msg":"trace[2140256233] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"1.556115055s","start":"2026-02-24T04:37:16.414512Z","end":"2026-02-24T04:37:17.970627Z","steps":["trace[2140256233] 'process raft request' (duration: 1.555750567s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:17.97072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:16.414496Z","time spent":"1.556175597s","remote":"127.0.0.1:50472","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-02-24T04:37:17.970955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.7607962s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T04:37:17.971006Z","caller":"traceutil/trace.go:171","msg":"trace[1880148082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:933; }","duration":"1.760894092s","start":"2026-02-24T04:37:16.210098Z","end":"2026-02-24T04:37:17.970992Z","steps":["trace[1880148082] 'agreement among raft nodes before linearized reading' (duration: 1.760758919s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:17.971033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:16.210077Z","time spent":"1.760951904s","remote":"127.0.0.1:50472","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-02-24T04:37:17.971182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.722858654s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T04:37:17.9712Z","caller":"traceutil/trace.go:171","msg":"trace[738501053] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:933; }","duration":"1.722906516s","start":"2026-02-24T04:37:16.248288Z","end":"2026-02-24T04:37:17.971194Z","steps":["trace[738501053] 'agreement among raft nodes before linearized reading' (duration: 1.722856705s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:17.971216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:16.24827Z","time spent":"1.722942206s","remote":"127.0.0.1:50472","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-02-24T04:37:17.971324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.761049576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T04:37:17.97134Z","caller":"traceutil/trace.go:171","msg":"trace[1626289860] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:933; }","duration":"1.761087397s","start":"2026-02-24T04:37:16.210248Z","end":"2026-02-24T04:37:17.971335Z","steps":["trace[1626289860] 'agreement among raft nodes before linearized reading' (duration: 1.761037706s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:17.971355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:16.210237Z","time spent":"1.761115028s","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T04:37:17.971638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.814114342s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T04:37:17.971661Z","caller":"traceutil/trace.go:171","msg":"trace[1597551895] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:933; }","duration":"1.814175434s","start":"2026-02-24T04:37:16.157477Z","end":"2026-02-24T04:37:17.971652Z","steps":["trace[1597551895] 'agreement among raft nodes before linearized reading' (duration: 1.814121613s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:17.971677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:16.157456Z","time spent":"1.814218014s","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T04:37:17.980617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:16.035565Z","time spent":"1.935058978s","remote":"127.0.0.1:50382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5205,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:37:17.982522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.571100413s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:37:17.98259Z","caller":"traceutil/trace.go:171","msg":"trace[1000406224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:933; }","duration":"1.571148244s","start":"2026-02-24T04:37:16.411398Z","end":"2026-02-24T04:37:17.982546Z","steps":["trace[1000406224] 'agreement among raft nodes before linearized reading' (duration: 1.571097353s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:17.982616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:16.411387Z","time spent":"1.571224136s","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:37:18.505637Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527001371286330,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:37:19.136738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.84099ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:37:19.136927Z","caller":"traceutil/trace.go:171","msg":"trace[2102071230] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:933; }","duration":"208.081436ms","start":"2026-02-24T04:37:18.928817Z","end":"2026-02-24T04:37:19.136898Z","steps":["trace[2102071230] 'range keys from in-memory index tree' (duration: 207.792889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:19.137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.650295ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:37:19.137545Z","caller":"traceutil/trace.go:171","msg":"trace[257866168] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"1.151641381s","start":"2026-02-24T04:37:17.985813Z","end":"2026-02-24T04:37:19.137454Z","steps":["trace[257866168] 'process raft request' (duration: 837.449474ms)","trace[257866168] 'compare' (duration: 313.537292ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:19.137664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:17.985799Z","time spent":"1.151826116s","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:37:19.988963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.9842778s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-24T04:37:19.989055Z","caller":"traceutil/trace.go:171","msg":"trace[1200886117] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.984398202s","start":"2026-02-24T04:37:18.004636Z","end":"2026-02-24T04:37:19.989034Z","steps":["trace[1200886117] 'agreement among raft nodes before linearized reading' (duration: 1.98429376s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:19.989101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:18.004628Z","time spent":"1.984463524s","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/24 04:37:19 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T04:37:20.431083Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.60793381s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T04:37:20.432027Z","caller":"traceutil/trace.go:171","msg":"trace[1060394376] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"2.427431437s","start":"2026-02-24T04:37:18.004571Z","end":"2026-02-24T04:37:20.432002Z","steps":["trace[1060394376] 'process raft request' (duration: 2.426659908s)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:20.432034Z","caller":"traceutil/trace.go:171","msg":"trace[787879959] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:977; }","duration":"2.427338534s","start":"2026-02-24T04:37:18.004667Z","end":"2026-02-24T04:37:20.432006Z","steps":["trace[787879959] 'read index received' (duration: 818.521362ms)","trace[787879959] 'applied index is now lower than readState.Index' (duration: 1.608816272s)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:20.432165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:18.004545Z","time spent":"2.427548619s","remote":"127.0.0.1:50472","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-02-24T04:37:20.432275Z","caller":"traceutil/trace.go:171","msg":"trace[85572992] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"2.426070924s","start":"2026-02-24T04:37:18.006194Z","end":"2026-02-24T04:37:20.432265Z","steps":["trace[85572992] 'process raft request' (duration: 2.425771937s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:20.43241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260106311s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T04:37:20.432461Z","caller":"traceutil/trace.go:171","msg":"trace[136625588] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:937; }","duration":"1.260215363s","start":"2026-02-24T04:37:19.172232Z","end":"2026-02-24T04:37:20.432447Z","steps":["trace[136625588] 'agreement among raft nodes before linearized reading' (duration: 1.260112121s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:20.432498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:19.172209Z","time spent":"1.260280104s","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:37:20.432616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.534108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-24T04:37:20.432662Z","caller":"traceutil/trace.go:171","msg":"trace[82079028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:937; }","duration":"446.67238ms","start":"2026-02-24T04:37:19.985977Z","end":"2026-02-24T04:37:20.432649Z","steps":["trace[82079028] 'agreement among raft nodes before linearized reading' (duration: 446.500596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:20.432693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.291223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T04:37:20.432696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:19.985954Z","time spent":"446.735582ms","remote":"127.0.0.1:50472","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-02-24T04:37:20.432726Z","caller":"traceutil/trace.go:171","msg":"trace[375797837] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:937; }","duration":"612.354945ms","start":"2026-02-24T04:37:19.820362Z","end":"2026-02-24T04:37:20.432717Z","steps":["trace[375797837] 'agreement among raft nodes before linearized reading' (duration: 612.304774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:20.432755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:19.820346Z","time spent":"612.401436ms","remote":"127.0.0.1:50624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:37:20.432743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.440194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:37:20.432766Z","caller":"traceutil/trace.go:171","msg":"trace[1271863549] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"2.428094693s","start":"2026-02-24T04:37:18.004657Z","end":"2026-02-24T04:37:20.432752Z","steps":["trace[1271863549] 'process raft request' (duration: 2.427200201s)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:20.432795Z","caller":"traceutil/trace.go:171","msg":"trace[1116478331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:937; }","duration":"443.537426ms","start":"2026-02-24T04:37:19.989241Z","end":"2026-02-24T04:37:20.432778Z","steps":["trace[1116478331] 'agreement among raft nodes before linearized reading' (duration: 443.443164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:20.432837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:19.989222Z","time spent":"443.607288ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:37:20.432869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:18.00465Z","time spent":"2.428150664s","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:37:20.432359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:18.006188Z","time spent":"2.426107095s","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:37:20.681791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.498596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:37:20.681883Z","caller":"traceutil/trace.go:171","msg":"trace[494305640] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:982; }","duration":"241.017721ms","start":"2026-02-24T04:37:20.44085Z","end":"2026-02-24T04:37:20.681867Z","steps":["trace[494305640] 'read index received' (duration: 21.35936ms)","trace[494305640] 'applied index is now lower than readState.Index' (duration: 219.657371ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:20.68203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.182205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:37:20.682055Z","caller":"traceutil/trace.go:171","msg":"trace[1643176290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"241.252387ms","start":"2026-02-24T04:37:20.440796Z","end":"2026-02-24T04:37:20.682048Z","steps":["trace[1643176290] 'agreement among raft nodes before linearized reading' (duration: 241.158344ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:20.681905Z","caller":"traceutil/trace.go:171","msg":"trace[1708917841] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"244.890183ms","start":"2026-02-24T04:37:20.436982Z","end":"2026-02-24T04:37:20.681872Z","steps":["trace[1708917841] 'process raft request' (duration: 142.245194ms)","trace[1708917841] 'compare' (duration: 102.369623ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:20.988349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.501015ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-24T04:37:21.285739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.419483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-24T04:37:21.285764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.026489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-24T04:37:21.285873Z","caller":"traceutil/trace.go:171","msg":"trace[1391654884] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:940; }","duration":"196.172982ms","start":"2026-02-24T04:37:21.089666Z","end":"2026-02-24T04:37:21.285839Z","steps":["trace[1391654884] 'range keys from in-memory index tree' (duration: 195.870665ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:21.285856Z","caller":"traceutil/trace.go:171","msg":"trace[1028602584] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:940; }","duration":"143.604097ms","start":"2026-02-24T04:37:21.142227Z","end":"2026-02-24T04:37:21.285831Z","steps":["trace[1028602584] 'range keys from in-memory index tree' (duration: 143.195628ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:21.542348Z","caller":"traceutil/trace.go:171","msg":"trace[980941807] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:985; }","duration":"111.369168ms","start":"2026-02-24T04:37:21.430946Z","end":"2026-02-24T04:37:21.542315Z","steps":["trace[980941807] 'read index received' (duration: 110.817925ms)","trace[980941807] 'applied index is now lower than readState.Index' (duration: 547.773µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:21.542451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.48973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:37:21.542495Z","caller":"traceutil/trace.go:171","msg":"trace[254193182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"111.591063ms","start":"2026-02-24T04:37:21.430896Z","end":"2026-02-24T04:37:21.542487Z","steps":["trace[254193182] 'agreement among raft nodes before linearized reading' (duration: 111.48818ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:21.542361Z","caller":"traceutil/trace.go:171","msg":"trace[1179626783] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"251.771048ms","start":"2026-02-24T04:37:21.290567Z","end":"2026-02-24T04:37:21.542338Z","steps":["trace[1179626783] 'process raft request' (duration: 251.289187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:22.920026Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527001371286349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:37:23.093319Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.075945374s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T04:37:23.093638Z","caller":"traceutil/trace.go:171","msg":"trace[734046485] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"1.076310823s","start":"2026-02-24T04:37:22.017303Z","end":"2026-02-24T04:37:23.093613Z","steps":["trace[734046485] 'process raft request' (duration: 1.076173079s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:23.093787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:22.017284Z","time spent":"1.076432765s","remote":"127.0.0.1:50268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":873,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T04:37:24.419076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000126827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-24T04:37:24.419135Z","caller":"traceutil/trace.go:171","msg":"trace[661687665] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00023694s","start":"2026-02-24T04:37:22.418883Z","end":"2026-02-24T04:37:24.41912Z","steps":["trace[661687665] 'agreement among raft nodes before linearized reading' (duration: 2.000162978s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:24.419191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:22.418861Z","time spent":"2.000324893s","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/24 04:37:24 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T04:37:24.491647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.168202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:37:24.491741Z","caller":"traceutil/trace.go:171","msg":"trace[1113533875] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:986; }","duration":"2.072797572s","start":"2026-02-24T04:37:22.418928Z","end":"2026-02-24T04:37:24.491725Z","steps":["trace[1113533875] 'read index received' (duration: 674.561865ms)","trace[1113533875] 'applied index is now lower than readState.Index' (duration: 1.398234537s)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:24.491966Z","caller":"traceutil/trace.go:171","msg":"trace[1164870401] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"1.929569724s","start":"2026-02-24T04:37:22.562384Z","end":"2026-02-24T04:37:24.491954Z","steps":["trace[1164870401] 'process raft request' (duration: 1.425015243s)","trace[1164870401] 'compare' (duration: 504.07603ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:24.492032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:22.56236Z","time spent":"1.929640766s","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:37:24.492194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.056206276s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-24T04:37:24.492249Z","caller":"traceutil/trace.go:171","msg":"trace[2101494550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:943; }","duration":"2.056305589s","start":"2026-02-24T04:37:22.43593Z","end":"2026-02-24T04:37:24.492236Z","steps":["trace[2101494550] 'agreement among raft nodes before linearized reading' (duration: 2.056146655s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:24.492285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:22.435909Z","time spent":"2.05636738s","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T04:37:24.492417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.026325383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-24T04:37:24.492422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.307635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-24T04:37:24.49245Z","caller":"traceutil/trace.go:171","msg":"trace[1706518360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:943; }","duration":"2.026383145s","start":"2026-02-24T04:37:22.466058Z","end":"2026-02-24T04:37:24.492441Z","steps":["trace[1706518360] 'agreement among raft nodes before linearized reading' (duration: 2.026299983s)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:24.492455Z","caller":"traceutil/trace.go:171","msg":"trace[1999746955] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:943; }","duration":"944.405808ms","start":"2026-02-24T04:37:23.548039Z","end":"2026-02-24T04:37:24.492445Z","steps":["trace[1999746955] 'agreement among raft nodes before linearized reading' (duration: 944.314176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:24.492478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:22.466042Z","time spent":"2.026429065s","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T04:37:24.492483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:23.548005Z","time spent":"944.471269ms","remote":"127.0.0.1:50472","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-02-24T04:37:24.492527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.37576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T04:37:24.492598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.056189945s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-24T04:37:24.49262Z","caller":"traceutil/trace.go:171","msg":"trace[901358981] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:943; }","duration":"567.482002ms","start":"2026-02-24T04:37:23.925111Z","end":"2026-02-24T04:37:24.492593Z","steps":["trace[901358981] 'agreement among raft nodes before linearized reading' (duration: 567.313498ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:24.492631Z","caller":"traceutil/trace.go:171","msg":"trace[1047646202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:943; }","duration":"2.056263177s","start":"2026-02-24T04:37:22.43636Z","end":"2026-02-24T04:37:24.492623Z","steps":["trace[1047646202] 'agreement among raft nodes before linearized reading' (duration: 2.056185845s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:24.492659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:22.436334Z","time spent":"2.056319429s","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T04:37:24.492685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:23.925084Z","time spent":"567.579025ms","remote":"127.0.0.1:50382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:37:24.492699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.055074929s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-24T04:37:24.492792Z","caller":"traceutil/trace.go:171","msg":"trace[2083087139] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:943; }","duration":"2.055194971s","start":"2026-02-24T04:37:22.437575Z","end":"2026-02-24T04:37:24.49277Z","steps":["trace[2083087139] 'agreement among raft nodes before linearized reading' (duration: 2.054954296s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:24.492854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:22.437567Z","time spent":"2.055273033s","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T04:37:24.998747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.360571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:37:24.99883Z","caller":"traceutil/trace.go:171","msg":"trace[391298585] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"501.816706ms","start":"2026-02-24T04:37:24.497Z","end":"2026-02-24T04:37:24.998817Z","steps":["trace[391298585] 'process raft request' (duration: 353.325552ms)","trace[391298585] 'compare' (duration: 148.279329ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:24.998885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:24.496971Z","time spent":"501.883007ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:37:25.578805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.389231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:37:25.579043Z","caller":"traceutil/trace.go:171","msg":"trace[433674602] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"1.081323585s","start":"2026-02-24T04:37:24.497702Z","end":"2026-02-24T04:37:25.579026Z","steps":["trace[433674602] 'process raft request' (duration: 1.081258253s)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:25.579098Z","caller":"traceutil/trace.go:171","msg":"trace[1112209103] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"1.081759815s","start":"2026-02-24T04:37:24.497314Z","end":"2026-02-24T04:37:25.579074Z","steps":["trace[1112209103] 'process raft request' (duration: 669.026076ms)","trace[1112209103] 'compare' (duration: 412.289299ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:25.579152Z","caller":"traceutil/trace.go:171","msg":"trace[756699012] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"1.081552601s","start":"2026-02-24T04:37:24.497592Z","end":"2026-02-24T04:37:25.579144Z","steps":["trace[756699012] 'process raft request' (duration: 1.081319155s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:25.579196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:24.497582Z","time spent":"1.081592222s","remote":"127.0.0.1:50472","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-02-24T04:37:25.579224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:24.497294Z","time spent":"1.081865178s","remote":"127.0.0.1:50472","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-02-24T04:37:25.579352Z","caller":"traceutil/trace.go:171","msg":"trace[1114618454] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:988; }","duration":"1.079521572s","start":"2026-02-24T04:37:24.499818Z","end":"2026-02-24T04:37:25.57934Z","steps":["trace[1114618454] 'read index received' (duration: 350.587657ms)","trace[1114618454] 'applied index is now lower than readState.Index' (duration: 728.933155ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:25.579499Z","caller":"traceutil/trace.go:171","msg":"trace[47021794] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"1.080347662s","start":"2026-02-24T04:37:24.499143Z","end":"2026-02-24T04:37:25.579491Z","steps":["trace[47021794] 'process raft request' (duration: 1.07985284s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:25.579547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:24.499135Z","time spent":"1.080384103s","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:37:25.579116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:24.497683Z","time spent":"1.081402296s","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:37:25.579694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079871091s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.189714cafbdcf323\" ","response":"range_response_count:1 size:774"} {"level":"warn","ts":"2026-02-24T04:37:25.57969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.07777283s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:37:25.579735Z","caller":"traceutil/trace.go:171","msg":"trace[1566576086] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.189714cafbdcf323; range_end:; response_count:1; response_revision:948; }","duration":"1.079926022s","start":"2026-02-24T04:37:24.499795Z","end":"2026-02-24T04:37:25.579721Z","steps":["trace[1566576086] 'agreement among raft nodes before linearized reading' (duration: 1.079817839s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:25.579761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:24.499786Z","time spent":"1.079968782s","remote":"127.0.0.1:50268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.189714cafbdcf323\" "} {"level":"info","ts":"2026-02-24T04:37:25.579753Z","caller":"traceutil/trace.go:171","msg":"trace[1915399946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:948; }","duration":"1.077860582s","start":"2026-02-24T04:37:24.501876Z","end":"2026-02-24T04:37:25.579737Z","steps":["trace[1915399946] 'agreement among raft nodes before linearized reading' (duration: 1.0777581s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:25.579796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:24.50187Z","time spent":"1.077920044s","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:37:26.476017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.573621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:37:26.47637Z","caller":"traceutil/trace.go:171","msg":"trace[810256337] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"892.043267ms","start":"2026-02-24T04:37:25.584302Z","end":"2026-02-24T04:37:26.476346Z","steps":["trace[810256337] 'process raft request' (duration: 405.011255ms)","trace[810256337] 'compare' (duration: 486.256354ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:26.476452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:25.584285Z","time spent":"892.12951ms","remote":"127.0.0.1:50268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:37:26.476736Z","caller":"traceutil/trace.go:171","msg":"trace[187890667] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"889.625689ms","start":"2026-02-24T04:37:25.5871Z","end":"2026-02-24T04:37:26.476726Z","steps":["trace[187890667] 'read index received' (duration: 402.113045ms)","trace[187890667] 'applied index is now lower than readState.Index' (duration: 487.508594ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:27.300666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.488413ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-24T04:37:27.300726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.713591021s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:37:27.300812Z","caller":"traceutil/trace.go:171","msg":"trace[1163470680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"1.713776606s","start":"2026-02-24T04:37:25.587013Z","end":"2026-02-24T04:37:27.30079Z","steps":["trace[1163470680] 'agreement among raft nodes before linearized reading' (duration: 889.748883ms)","trace[1163470680] 'range keys from in-memory index tree' (duration: 823.89557ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:27.300808Z","caller":"traceutil/trace.go:171","msg":"trace[1723806557] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:994; }","duration":"627.274349ms","start":"2026-02-24T04:37:26.673492Z","end":"2026-02-24T04:37:27.300767Z","steps":["trace[1723806557] 'read index received' (duration: 60.302µs)","trace[1723806557] 'applied index is now lower than readState.Index' (duration: 627.212377ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:27.300861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:25.587Z","time spent":"1.713852297s","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:37:27.301172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.678608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:37:27.30125Z","caller":"traceutil/trace.go:171","msg":"trace[485226686] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:949; }","duration":"627.812341ms","start":"2026-02-24T04:37:26.673418Z","end":"2026-02-24T04:37:27.30123Z","steps":["trace[485226686] 'agreement among raft nodes before linearized reading' (duration: 627.701069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:27.301291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:26.673373Z","time spent":"627.905313ms","remote":"127.0.0.1:35790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:37:27.301567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.089969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T04:37:27.301617Z","caller":"traceutil/trace.go:171","msg":"trace[1996727155] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:949; }","duration":"297.181521ms","start":"2026-02-24T04:37:27.004423Z","end":"2026-02-24T04:37:27.301604Z","steps":["trace[1996727155] 'agreement among raft nodes before linearized reading' (duration: 297.031538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:27.817356Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527001371286367,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T04:37:28.067597Z","caller":"traceutil/trace.go:171","msg":"trace[251493502] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"758.671494ms","start":"2026-02-24T04:37:27.308907Z","end":"2026-02-24T04:37:28.067579Z","steps":["trace[251493502] 'read index received' (duration: 758.642563ms)","trace[251493502] 'applied index is now lower than readState.Index' (duration: 28.201µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:28.06774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.813567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:37:28.067768Z","caller":"traceutil/trace.go:171","msg":"trace[1720396472] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"761.619154ms","start":"2026-02-24T04:37:27.306107Z","end":"2026-02-24T04:37:28.067726Z","steps":["trace[1720396472] 'process raft request' (duration: 761.363308ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:28.067854Z","caller":"traceutil/trace.go:171","msg":"trace[1661016290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"758.975981ms","start":"2026-02-24T04:37:27.30887Z","end":"2026-02-24T04:37:28.067846Z","steps":["trace[1661016290] 'agreement among raft nodes before linearized reading' (duration: 758.831118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:28.068399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:27.306084Z","time spent":"762.200909ms","remote":"127.0.0.1:50472","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-02-24T04:37:28.068507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:27.308852Z","time spent":"759.585666ms","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:37:28.364283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.207317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:37:28.364588Z","caller":"traceutil/trace.go:171","msg":"trace[1021380361] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"293.236948ms","start":"2026-02-24T04:37:28.071323Z","end":"2026-02-24T04:37:28.36456Z","steps":["trace[1021380361] 'process raft request' (duration: 293.162486ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:28.364748Z","caller":"traceutil/trace.go:171","msg":"trace[1658330312] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"521.639817ms","start":"2026-02-24T04:37:27.842969Z","end":"2026-02-24T04:37:28.364609Z","steps":["trace[1658330312] 'process raft request' (duration: 277.02407ms)","trace[1658330312] 'compare' (duration: 244.098345ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:28.365158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:27.84294Z","time spent":"521.976066ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:37:28.364922Z","caller":"traceutil/trace.go:171","msg":"trace[1914939698] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"297.204883ms","start":"2026-02-24T04:37:28.067686Z","end":"2026-02-24T04:37:28.364891Z","steps":["trace[1914939698] 'read index received' (duration: 52.222727ms)","trace[1914939698] 'applied index is now lower than readState.Index' (duration: 244.980106ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:28.365206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.945046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T04:37:28.365785Z","caller":"traceutil/trace.go:171","msg":"trace[1348820997] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:952; }","duration":"781.51401ms","start":"2026-02-24T04:37:27.584222Z","end":"2026-02-24T04:37:28.365736Z","steps":["trace[1348820997] 'agreement among raft nodes before linearized reading' (duration: 780.864715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:28.365904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:27.584208Z","time spent":"781.662303ms","remote":"127.0.0.1:50472","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-02-24T04:37:28.365986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.280227ms","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-02-24T04:37:28.366091Z","caller":"traceutil/trace.go:171","msg":"trace[728964024] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:952; }","duration":"101.40469ms","start":"2026-02-24T04:37:28.264663Z","end":"2026-02-24T04:37:28.366068Z","steps":["trace[728964024] 'agreement among raft nodes before linearized reading' (duration: 101.261767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:28.365348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.015918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T04:37:28.366173Z","caller":"traceutil/trace.go:171","msg":"trace[652961735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:952; }","duration":"781.844348ms","start":"2026-02-24T04:37:27.584286Z","end":"2026-02-24T04:37:28.36613Z","steps":["trace[652961735] 'agreement among raft nodes before linearized reading' (duration: 781.004788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:28.366231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:27.584267Z","time spent":"781.949251ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T04:37:28.365424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.841198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T04:37:28.366525Z","caller":"traceutil/trace.go:171","msg":"trace[1447605603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:952; }","duration":"782.826581ms","start":"2026-02-24T04:37:27.583577Z","end":"2026-02-24T04:37:28.366403Z","steps":["trace[1447605603] 'agreement among raft nodes before linearized reading' (duration: 781.802667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:28.3655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.003171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-24T04:37:28.366573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:27.583517Z","time spent":"783.043287ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T04:37:28.36663Z","caller":"traceutil/trace.go:171","msg":"trace[1652320429] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:952; }","duration":"783.133249ms","start":"2026-02-24T04:37:27.583464Z","end":"2026-02-24T04:37:28.366597Z","steps":["trace[1652320429] 'agreement among raft nodes before linearized reading' (duration: 781.990181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:28.367017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:27.583446Z","time spent":"783.225811ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T04:37:28.728663Z","caller":"traceutil/trace.go:171","msg":"trace[625012571] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"357.334827ms","start":"2026-02-24T04:37:28.371297Z","end":"2026-02-24T04:37:28.728632Z","steps":["trace[625012571] 'read index received' (duration: 295.664985ms)","trace[625012571] 'applied index is now lower than readState.Index' (duration: 61.667962ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:28.728835Z","caller":"traceutil/trace.go:171","msg":"trace[1291160232] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"358.497984ms","start":"2026-02-24T04:37:28.370321Z","end":"2026-02-24T04:37:28.728819Z","steps":["trace[1291160232] 'process raft request' (duration: 296.534716ms)","trace[1291160232] 'compare' (duration: 61.582749ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:28.728967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:28.370303Z","time spent":"358.576787ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:37:28.729226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.88989ms","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-02-24T04:37:28.729336Z","caller":"traceutil/trace.go:171","msg":"trace[278498942] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:954; }","duration":"358.059504ms","start":"2026-02-24T04:37:28.371249Z","end":"2026-02-24T04:37:28.729308Z","steps":["trace[278498942] 'agreement among raft nodes before linearized reading' (duration: 357.851339ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:28.729348Z","caller":"traceutil/trace.go:171","msg":"trace[1547090443] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"357.921051ms","start":"2026-02-24T04:37:28.371389Z","end":"2026-02-24T04:37:28.72931Z","steps":["trace[1547090443] 'process raft request' (duration: 357.200794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:28.72939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:28.371228Z","time spent":"358.152397ms","remote":"127.0.0.1:50284","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":"warn","ts":"2026-02-24T04:37:28.729568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:28.37138Z","time spent":"358.045834ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:37:28.729632Z","caller":"traceutil/trace.go:171","msg":"trace[1599505717] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"357.819108ms","start":"2026-02-24T04:37:28.371794Z","end":"2026-02-24T04:37:28.729613Z","steps":["trace[1599505717] 'process raft request' (duration: 357.302816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:28.729725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:28.371774Z","time spent":"357.909221ms","remote":"127.0.0.1:50472","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-02-24T04:37:28.731924Z","caller":"traceutil/trace.go:171","msg":"trace[1197856121] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"359.867287ms","start":"2026-02-24T04:37:28.372009Z","end":"2026-02-24T04:37:28.731877Z","steps":["trace[1197856121] 'process raft request' (duration: 357.253765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:28.73254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:28.371994Z","time spent":"360.017801ms","remote":"127.0.0.1:50472","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-02-24T04:37:28.733074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.002059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:37:28.733154Z","caller":"traceutil/trace.go:171","msg":"trace[1088494628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"313.097961ms","start":"2026-02-24T04:37:28.420029Z","end":"2026-02-24T04:37:28.733127Z","steps":["trace[1088494628] 'agreement among raft nodes before linearized reading' (duration: 311.634756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:28.733201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:28.420018Z","time spent":"313.171563ms","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:37:29.275135Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527001371286383,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:37:29.682244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.807749ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:37:29.68243Z","caller":"traceutil/trace.go:171","msg":"trace[615520822] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:958; }","duration":"754.009903ms","start":"2026-02-24T04:37:28.928388Z","end":"2026-02-24T04:37:29.682398Z","steps":["trace[615520822] 'range keys from in-memory index tree' (duration: 753.775758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:29.682378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.298883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:37:29.683049Z","caller":"traceutil/trace.go:171","msg":"trace[922680720] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"944.992481ms","start":"2026-02-24T04:37:28.738021Z","end":"2026-02-24T04:37:29.683014Z","steps":["trace[922680720] 'process raft request' (duration: 605.973401ms)","trace[922680720] 'compare' (duration: 338.086518ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:29.683202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:28.737982Z","time spent":"945.178086ms","remote":"127.0.0.1:50268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T04:37:29.683767Z","caller":"traceutil/trace.go:171","msg":"trace[183078967] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1004; }","duration":"909.548826ms","start":"2026-02-24T04:37:28.774206Z","end":"2026-02-24T04:37:29.683755Z","steps":["trace[183078967] 'read index received' (duration: 569.798088ms)","trace[183078967] 'applied index is now lower than readState.Index' (duration: 339.749718ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:29.683807Z","caller":"traceutil/trace.go:171","msg":"trace[1190025348] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"907.538488ms","start":"2026-02-24T04:37:28.776252Z","end":"2026-02-24T04:37:29.68379Z","steps":["trace[1190025348] 'process raft request' (duration: 906.727968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:29.684088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.723573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T04:37:29.684119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:28.776231Z","time spent":"907.751842ms","remote":"127.0.0.1:50268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T04:37:29.684202Z","caller":"traceutil/trace.go:171","msg":"trace[232868309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"263.842406ms","start":"2026-02-24T04:37:29.420304Z","end":"2026-02-24T04:37:29.684146Z","steps":["trace[232868309] 'agreement among raft nodes before linearized reading' (duration: 263.704583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:29.684694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.480948ms","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-02-24T04:37:29.684784Z","caller":"traceutil/trace.go:171","msg":"trace[221249531] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:960; }","duration":"910.620691ms","start":"2026-02-24T04:37:28.77415Z","end":"2026-02-24T04:37:29.684771Z","steps":["trace[221249531] 'agreement among raft nodes before linearized reading' (duration: 909.704089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:29.684841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:28.774127Z","time spent":"910.703323ms","remote":"127.0.0.1:50472","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":"info","ts":"2026-02-24T04:37:32.369453Z","caller":"traceutil/trace.go:171","msg":"trace[204158262] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"132.473321ms","start":"2026-02-24T04:37:32.236954Z","end":"2026-02-24T04:37:32.369428Z","steps":["trace[204158262] 'process raft request' (duration: 126.592731ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:32.889661Z","caller":"traceutil/trace.go:171","msg":"trace[795650651] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"139.341345ms","start":"2026-02-24T04:37:32.750294Z","end":"2026-02-24T04:37:32.889635Z","steps":["trace[795650651] 'process raft request' (duration: 139.176871ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:33.272861Z","caller":"traceutil/trace.go:171","msg":"trace[512514163] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"522.52977ms","start":"2026-02-24T04:37:32.750289Z","end":"2026-02-24T04:37:33.272819Z","steps":["trace[512514163] 'process raft request' (duration: 473.119831ms)","trace[512514163] 'compare' (duration: 49.250595ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:33.273059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:32.75027Z","time spent":"522.705654ms","remote":"127.0.0.1:50472","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-02-24T04:37:33.273087Z","caller":"traceutil/trace.go:171","msg":"trace[743297939] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"522.011127ms","start":"2026-02-24T04:37:32.751063Z","end":"2026-02-24T04:37:33.273074Z","steps":["trace[743297939] 'process raft request' (duration: 521.892874ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:33.273064Z","caller":"traceutil/trace.go:171","msg":"trace[1440189203] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"522.588751ms","start":"2026-02-24T04:37:32.750452Z","end":"2026-02-24T04:37:33.273041Z","steps":["trace[1440189203] 'process raft request' (duration: 522.325285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:33.273193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:32.751052Z","time spent":"522.070088ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:37:33.273195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:32.750443Z","time spent":"522.718724ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:37:45.177835Z","caller":"traceutil/trace.go:171","msg":"trace[329594065] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"112.005723ms","start":"2026-02-24T04:37:45.065805Z","end":"2026-02-24T04:37:45.177811Z","steps":["trace[329594065] 'process raft request' (duration: 111.86162ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:48.657655Z","caller":"traceutil/trace.go:171","msg":"trace[1726059935] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"125.000843ms","start":"2026-02-24T04:37:48.532631Z","end":"2026-02-24T04:37:48.657632Z","steps":["trace[1726059935] 'process raft request' (duration: 124.845899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:48.944875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.739199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T04:37:48.944937Z","caller":"traceutil/trace.go:171","msg":"trace[1625346683] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1062; }","duration":"122.859612ms","start":"2026-02-24T04:37:48.822064Z","end":"2026-02-24T04:37:48.944923Z","steps":["trace[1625346683] 'count revisions from in-memory index tree' (duration: 122.660557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:38:10.824075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.394985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.141\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T04:38:10.824183Z","caller":"traceutil/trace.go:171","msg":"trace[1960872947] range","detail":"{range_begin:/registry/masterleases/199.204.45.141; range_end:; response_count:1; response_revision:1152; }","duration":"110.549678ms","start":"2026-02-24T04:38:10.713609Z","end":"2026-02-24T04:38:10.824159Z","steps":["trace[1960872947] 'range keys from in-memory index tree' (duration: 110.146619ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:38:47.647892Z","caller":"traceutil/trace.go:171","msg":"trace[1291981462] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"152.924112ms","start":"2026-02-24T04:38:47.494944Z","end":"2026-02-24T04:38:47.647868Z","steps":["trace[1291981462] 'process raft request' (duration: 152.729917ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:38:48.619882Z","caller":"traceutil/trace.go:171","msg":"trace[1007046716] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"131.784296ms","start":"2026-02-24T04:38:48.488084Z","end":"2026-02-24T04:38:48.619868Z","steps":["trace[1007046716] 'process raft request' (duration: 131.714094ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:38:50.214106Z","caller":"traceutil/trace.go:171","msg":"trace[929198565] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1372; }","duration":"123.076846ms","start":"2026-02-24T04:38:50.091005Z","end":"2026-02-24T04:38:50.214082Z","steps":["trace[929198565] 'read index received' (duration: 93.559449ms)","trace[929198565] 'applied index is now lower than readState.Index' (duration: 29.516597ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:38:50.214408Z","caller":"traceutil/trace.go:171","msg":"trace[1016548775] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"170.428714ms","start":"2026-02-24T04:38:50.043969Z","end":"2026-02-24T04:38:50.214397Z","steps":["trace[1016548775] 'process raft request' (duration: 140.553218ms)","trace[1016548775] 'compare' (duration: 29.372294ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:38:50.214406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.361173ms","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-02-24T04:38:50.214495Z","caller":"traceutil/trace.go:171","msg":"trace[762404064] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1311; }","duration":"123.567207ms","start":"2026-02-24T04:38:50.090903Z","end":"2026-02-24T04:38:50.21447Z","steps":["trace[762404064] 'agreement among raft nodes before linearized reading' (duration: 123.318752ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:38:50.961304Z","caller":"traceutil/trace.go:171","msg":"trace[1861522506] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"138.020529ms","start":"2026-02-24T04:38:50.823236Z","end":"2026-02-24T04:38:50.961257Z","steps":["trace[1861522506] 'process raft request' (duration: 91.90646ms)","trace[1861522506] 'compare' (duration: 45.952435ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:38:51.793147Z","caller":"traceutil/trace.go:171","msg":"trace[638988882] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"125.999923ms","start":"2026-02-24T04:38:51.667124Z","end":"2026-02-24T04:38:51.793124Z","steps":["trace[638988882] 'process raft request' (duration: 125.853719ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:38:52.074862Z","caller":"traceutil/trace.go:171","msg":"trace[1179206113] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"115.482332ms","start":"2026-02-24T04:38:51.959349Z","end":"2026-02-24T04:38:52.074831Z","steps":["trace[1179206113] 'process raft request' (duration: 115.329359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:38:53.171609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.6045ms","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:509"} {"level":"info","ts":"2026-02-24T04:38:53.171655Z","caller":"traceutil/trace.go:171","msg":"trace[1219746648] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1322; }","duration":"191.681071ms","start":"2026-02-24T04:38:52.97996Z","end":"2026-02-24T04:38:53.171641Z","steps":["trace[1219746648] 'range keys from in-memory index tree' (duration: 191.502997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:38:53.171599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.696147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T04:38:53.171706Z","caller":"traceutil/trace.go:171","msg":"trace[1704065087] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1322; }","duration":"291.855811ms","start":"2026-02-24T04:38:52.879846Z","end":"2026-02-24T04:38:53.171702Z","steps":["trace[1704065087] 'range keys from in-memory index tree' (duration: 291.598675ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:38:53.285936Z","caller":"traceutil/trace.go:171","msg":"trace[1150866104] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"110.329003ms","start":"2026-02-24T04:38:53.175586Z","end":"2026-02-24T04:38:53.285915Z","steps":["trace[1150866104] 'process raft request' (duration: 100.965128ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:38:53.286055Z","caller":"traceutil/trace.go:171","msg":"trace[1720654263] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"110.414276ms","start":"2026-02-24T04:38:53.175622Z","end":"2026-02-24T04:38:53.286036Z","steps":["trace[1720654263] 'process raft request' (duration: 110.258772ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:38:54.657879Z","caller":"traceutil/trace.go:171","msg":"trace[1034886047] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"260.450771ms","start":"2026-02-24T04:38:54.397363Z","end":"2026-02-24T04:38:54.657814Z","steps":["trace[1034886047] 'process raft request' (duration: 260.138563ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:38:54.658149Z","caller":"traceutil/trace.go:171","msg":"trace[1416737197] linearizableReadLoop","detail":"{readStateIndex:1392; appliedIndex:1391; }","duration":"222.625332ms","start":"2026-02-24T04:38:54.435498Z","end":"2026-02-24T04:38:54.658124Z","steps":["trace[1416737197] 'read index received' (duration: 221.925386ms)","trace[1416737197] 'applied index is now lower than readState.Index' (duration: 697.526µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:38:54.658333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.814316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:38:54.658406Z","caller":"traceutil/trace.go:171","msg":"trace[1089874987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1329; }","duration":"222.927249ms","start":"2026-02-24T04:38:54.435463Z","end":"2026-02-24T04:38:54.658391Z","steps":["trace[1089874987] 'agreement among raft nodes before linearized reading' (duration: 222.820446ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:39:03.432838Z","caller":"traceutil/trace.go:171","msg":"trace[1827820778] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"116.844593ms","start":"2026-02-24T04:39:03.315966Z","end":"2026-02-24T04:39:03.432811Z","steps":["trace[1827820778] 'process raft request' (duration: 116.658989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:39:50.908406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.818286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.141\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T04:39:50.908518Z","caller":"traceutil/trace.go:171","msg":"trace[1046626136] range","detail":"{range_begin:/registry/masterleases/199.204.45.141; range_end:; response_count:1; response_revision:1556; }","duration":"188.945148ms","start":"2026-02-24T04:39:50.719545Z","end":"2026-02-24T04:39:50.90849Z","steps":["trace[1046626136] 'range keys from in-memory index tree' (duration: 188.414331ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:39:51.068039Z","caller":"traceutil/trace.go:171","msg":"trace[1218207145] transaction","detail":"{read_only:false; response_revision:1558; number_of_response:1; }","duration":"117.886966ms","start":"2026-02-24T04:39:50.950116Z","end":"2026-02-24T04:39:51.068002Z","steps":["trace[1218207145] 'process raft request' (duration: 107.49853ms)","trace[1218207145] 'compare' (duration: 10.146113ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:39:51.219572Z","caller":"traceutil/trace.go:171","msg":"trace[630023915] transaction","detail":"{read_only:false; response_revision:1559; number_of_response:1; }","duration":"147.179791ms","start":"2026-02-24T04:39:51.072363Z","end":"2026-02-24T04:39:51.219543Z","steps":["trace[630023915] 'process raft request' (duration: 146.407951ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:39:51.219992Z","caller":"traceutil/trace.go:171","msg":"trace[1550027669] linearizableReadLoop","detail":"{readStateIndex:1635; appliedIndex:1633; }","duration":"146.652813ms","start":"2026-02-24T04:39:51.073306Z","end":"2026-02-24T04:39:51.219959Z","steps":["trace[1550027669] 'read index received' (duration: 145.484678ms)","trace[1550027669] 'applied index is now lower than readState.Index' (duration: 1.167385ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:39:51.220185Z","caller":"traceutil/trace.go:171","msg":"trace[1910479349] transaction","detail":"{read_only:false; response_revision:1560; number_of_response:1; }","duration":"147.502805ms","start":"2026-02-24T04:39:51.072671Z","end":"2026-02-24T04:39:51.220174Z","steps":["trace[1910479349] 'process raft request' (duration: 147.158731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:39:51.220595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.273631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-24T04:39:51.220639Z","caller":"traceutil/trace.go:171","msg":"trace[414159590] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1560; }","duration":"147.344622ms","start":"2026-02-24T04:39:51.073283Z","end":"2026-02-24T04:39:51.220628Z","steps":["trace[414159590] 'agreement among raft nodes before linearized reading' (duration: 147.057788ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:39:51.930769Z","caller":"traceutil/trace.go:171","msg":"trace[865215560] transaction","detail":"{read_only:false; response_revision:1563; number_of_response:1; }","duration":"137.881108ms","start":"2026-02-24T04:39:51.792859Z","end":"2026-02-24T04:39:51.93074Z","steps":["trace[865215560] 'process raft request' (duration: 111.31602ms)","trace[865215560] 'compare' (duration: 26.362525ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:39:55.929265Z","caller":"traceutil/trace.go:171","msg":"trace[49024191] transaction","detail":"{read_only:false; response_revision:1578; number_of_response:1; }","duration":"138.211992ms","start":"2026-02-24T04:39:55.79103Z","end":"2026-02-24T04:39:55.929242Z","steps":["trace[49024191] 'process raft request' (duration: 138.01834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:39:56.266545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.276465ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T04:39:56.4826Z","caller":"traceutil/trace.go:171","msg":"trace[868207909] transaction","detail":"{read_only:false; response_revision:1580; number_of_response:1; }","duration":"151.452627ms","start":"2026-02-24T04:39:56.331117Z","end":"2026-02-24T04:39:56.48257Z","steps":["trace[868207909] 'process raft request' (duration: 61.122682ms)","trace[868207909] 'compare' (duration: 89.97607ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:39:57.571397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.53253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:39:57.571949Z","caller":"traceutil/trace.go:171","msg":"trace[1057445494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1584; }","duration":"153.051708ms","start":"2026-02-24T04:39:57.418783Z","end":"2026-02-24T04:39:57.571834Z","steps":["trace[1057445494] 'range keys from in-memory index tree' (duration: 152.437619ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:39:57.57155Z","caller":"traceutil/trace.go:171","msg":"trace[1112560395] transaction","detail":"{read_only:false; response_revision:1585; number_of_response:1; }","duration":"125.140222ms","start":"2026-02-24T04:39:57.446378Z","end":"2026-02-24T04:39:57.571518Z","steps":["trace[1112560395] 'process raft request' (duration: 72.361589ms)","trace[1112560395] 'compare' (duration: 52.660291ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:40:13.553633Z","caller":"traceutil/trace.go:171","msg":"trace[916935036] transaction","detail":"{read_only:false; response_revision:1651; number_of_response:1; }","duration":"101.125149ms","start":"2026-02-24T04:40:13.452484Z","end":"2026-02-24T04:40:13.553609Z","steps":["trace[916935036] 'process raft request' (duration: 101.002696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:40:21.385059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.307108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T04:40:21.385099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.17544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T04:40:21.385138Z","caller":"traceutil/trace.go:171","msg":"trace[696110076] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1681; }","duration":"187.495942ms","start":"2026-02-24T04:40:21.197628Z","end":"2026-02-24T04:40:21.385124Z","steps":["trace[696110076] 'count revisions from in-memory index tree' (duration: 187.151484ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:40:21.385164Z","caller":"traceutil/trace.go:171","msg":"trace[21269459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1681; }","duration":"237.293723ms","start":"2026-02-24T04:40:21.14785Z","end":"2026-02-24T04:40:21.385144Z","steps":["trace[21269459] 'range keys from in-memory index tree' (duration: 237.024527ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:40:21.774769Z","caller":"traceutil/trace.go:171","msg":"trace[936805645] transaction","detail":"{read_only:false; response_revision:1682; number_of_response:1; }","duration":"386.983494ms","start":"2026-02-24T04:40:21.387759Z","end":"2026-02-24T04:40:21.774742Z","steps":["trace[936805645] 'process raft request' (duration: 386.80174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:40:21.774935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:40:21.387743Z","time spent":"387.110267ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:40:21.89079Z","caller":"traceutil/trace.go:171","msg":"trace[1043777523] transaction","detail":"{read_only:false; response_revision:1683; number_of_response:1; }","duration":"502.658416ms","start":"2026-02-24T04:40:21.388073Z","end":"2026-02-24T04:40:21.890732Z","steps":["trace[1043777523] 'process raft request' (duration: 477.311009ms)","trace[1043777523] 'compare' (duration: 25.188923ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:40:21.890942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.115734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-02-24T04:40:21.89095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:40:21.388058Z","time spent":"502.81567ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:40:21.890968Z","caller":"traceutil/trace.go:171","msg":"trace[254457301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1683; }","duration":"437.178815ms","start":"2026-02-24T04:40:21.453781Z","end":"2026-02-24T04:40:21.89096Z","steps":["trace[254457301] 'agreement among raft nodes before linearized reading' (duration: 437.072802ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:40:21.89083Z","caller":"traceutil/trace.go:171","msg":"trace[1197797938] linearizableReadLoop","detail":"{readStateIndex:1764; appliedIndex:1762; }","duration":"436.990471ms","start":"2026-02-24T04:40:21.453821Z","end":"2026-02-24T04:40:21.890812Z","steps":["trace[1197797938] 'read index received' (duration: 320.824808ms)","trace[1197797938] 'applied index is now lower than readState.Index' (duration: 116.163433ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:40:21.890988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:40:21.453763Z","time spent":"437.221816ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T04:40:21.891286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.738387ms","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:533"} {"level":"info","ts":"2026-02-24T04:40:21.891359Z","caller":"traceutil/trace.go:171","msg":"trace[1284219006] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1683; }","duration":"275.86288ms","start":"2026-02-24T04:40:21.615482Z","end":"2026-02-24T04:40:21.891345Z","steps":["trace[1284219006] 'agreement among raft nodes before linearized reading' (duration: 275.698796ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:40:22.023016Z","caller":"traceutil/trace.go:171","msg":"trace[797834339] transaction","detail":"{read_only:false; response_revision:1685; number_of_response:1; }","duration":"126.556755ms","start":"2026-02-24T04:40:21.896436Z","end":"2026-02-24T04:40:22.022993Z","steps":["trace[797834339] 'process raft request' (duration: 125.517551ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:40:22.37124Z","caller":"traceutil/trace.go:171","msg":"trace[2016560620] transaction","detail":"{read_only:false; response_revision:1686; number_of_response:1; }","duration":"132.784893ms","start":"2026-02-24T04:40:22.238423Z","end":"2026-02-24T04:40:22.371208Z","steps":["trace[2016560620] 'process raft request' (duration: 132.619779ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:40:22.829345Z","caller":"traceutil/trace.go:171","msg":"trace[265224262] transaction","detail":"{read_only:false; response_revision:1688; number_of_response:1; }","duration":"110.636942ms","start":"2026-02-24T04:40:22.718678Z","end":"2026-02-24T04:40:22.829315Z","steps":["trace[265224262] 'process raft request' (duration: 61.882086ms)","trace[265224262] 'compare' (duration: 48.633064ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:40:23.850063Z","caller":"traceutil/trace.go:171","msg":"trace[1728793753] transaction","detail":"{read_only:false; response_revision:1691; number_of_response:1; }","duration":"201.659074ms","start":"2026-02-24T04:40:23.648374Z","end":"2026-02-24T04:40:23.850033Z","steps":["trace[1728793753] 'process raft request' (duration: 178.198912ms)","trace[1728793753] 'compare' (duration: 23.123244ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:40:24.073569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.445044ms","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:533"} {"level":"info","ts":"2026-02-24T04:40:24.073626Z","caller":"traceutil/trace.go:171","msg":"trace[1497834392] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1692; }","duration":"117.553896ms","start":"2026-02-24T04:40:23.956059Z","end":"2026-02-24T04:40:24.073613Z","steps":["trace[1497834392] 'range keys from in-memory index tree' (duration: 117.277339ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:40:24.285922Z","caller":"traceutil/trace.go:171","msg":"trace[498287080] transaction","detail":"{read_only:false; response_revision:1694; number_of_response:1; }","duration":"207.869081ms","start":"2026-02-24T04:40:24.078023Z","end":"2026-02-24T04:40:24.285892Z","steps":["trace[498287080] 'process raft request' (duration: 136.87267ms)","trace[498287080] 'compare' (duration: 70.854737ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:40:24.516786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.621369ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:40:24.517003Z","caller":"traceutil/trace.go:171","msg":"trace[1140565212] transaction","detail":"{read_only:false; response_revision:1695; number_of_response:1; }","duration":"137.69254ms","start":"2026-02-24T04:40:24.379281Z","end":"2026-02-24T04:40:24.516973Z","steps":["trace[1140565212] 'process raft request' (duration: 27.791864ms)","trace[1140565212] 'compare' (duration: 109.486226ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:40:26.615473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.847071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:40:26.615566Z","caller":"traceutil/trace.go:171","msg":"trace[1377400106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1701; }","duration":"197.005215ms","start":"2026-02-24T04:40:26.418539Z","end":"2026-02-24T04:40:26.615545Z","steps":["trace[1377400106] 'range keys from in-memory index tree' (duration: 196.77758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:40:26.84658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.261754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-24T04:40:26.84667Z","caller":"traceutil/trace.go:171","msg":"trace[2135661251] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1702; }","duration":"107.416917ms","start":"2026-02-24T04:40:26.739235Z","end":"2026-02-24T04:40:26.846652Z","steps":["trace[2135661251] 'range keys from in-memory index tree' (duration: 107.057999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:40:27.138675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.900964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:40:27.139029Z","caller":"traceutil/trace.go:171","msg":"trace[507008367] transaction","detail":"{read_only:false; response_revision:1704; number_of_response:1; }","duration":"206.54989ms","start":"2026-02-24T04:40:26.932455Z","end":"2026-02-24T04:40:27.139005Z","steps":["trace[507008367] 'process raft request' (duration: 57.243906ms)","trace[507008367] 'compare' (duration: 148.749931ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:40:42.998657Z","caller":"traceutil/trace.go:171","msg":"trace[1574140463] linearizableReadLoop","detail":"{readStateIndex:1872; appliedIndex:1871; }","duration":"132.20552ms","start":"2026-02-24T04:40:42.866422Z","end":"2026-02-24T04:40:42.998628Z","steps":["trace[1574140463] 'read index received' (duration: 124.610481ms)","trace[1574140463] 'applied index is now lower than readState.Index' (duration: 7.593699ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:40:42.998703Z","caller":"traceutil/trace.go:171","msg":"trace[1196327891] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"146.556678ms","start":"2026-02-24T04:40:42.852118Z","end":"2026-02-24T04:40:42.998675Z","steps":["trace[1196327891] 'process raft request' (duration: 138.909628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:40:42.998877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.428576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-02-24T04:40:42.99893Z","caller":"traceutil/trace.go:171","msg":"trace[641867889] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:1786; }","duration":"132.530668ms","start":"2026-02-24T04:40:42.866388Z","end":"2026-02-24T04:40:42.998919Z","steps":["trace[641867889] 'agreement among raft nodes before linearized reading' (duration: 132.403675ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:40:48.66271Z","caller":"traceutil/trace.go:171","msg":"trace[150798217] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"109.230769ms","start":"2026-02-24T04:40:48.553446Z","end":"2026-02-24T04:40:48.662677Z","steps":["trace[150798217] 'process raft request' (duration: 81.488297ms)","trace[150798217] 'compare' (duration: 27.595129ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:41:04.452932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.084175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T04:41:04.45301Z","caller":"traceutil/trace.go:171","msg":"trace[2032846660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1885; }","duration":"120.206178ms","start":"2026-02-24T04:41:04.332788Z","end":"2026-02-24T04:41:04.452995Z","steps":["trace[2032846660] 'range keys from in-memory index tree' (duration: 119.900251ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:41:04.626564Z","caller":"traceutil/trace.go:171","msg":"trace[481925080] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"170.595423ms","start":"2026-02-24T04:41:04.455951Z","end":"2026-02-24T04:41:04.626546Z","steps":["trace[481925080] 'process raft request' (duration: 170.356238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:41:04.860071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.261865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:41:04.860199Z","caller":"traceutil/trace.go:171","msg":"trace[439714182] linearizableReadLoop","detail":"{readStateIndex:1977; appliedIndex:1976; }","duration":"100.585685ms","start":"2026-02-24T04:41:04.759594Z","end":"2026-02-24T04:41:04.860179Z","steps":["trace[439714182] 'read index received' (duration: 36.84µs)","trace[439714182] 'applied index is now lower than readState.Index' (duration: 100.547055ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:41:04.860287Z","caller":"traceutil/trace.go:171","msg":"trace[56334676] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"229.067828ms","start":"2026-02-24T04:41:04.631208Z","end":"2026-02-24T04:41:04.860275Z","steps":["trace[56334676] 'process raft request' (duration: 69.442654ms)","trace[56334676] 'compare' (duration: 159.124392ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:41:04.860719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.120038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-24T04:41:04.860819Z","caller":"traceutil/trace.go:171","msg":"trace[1266677643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1887; }","duration":"101.224571ms","start":"2026-02-24T04:41:04.759542Z","end":"2026-02-24T04:41:04.860766Z","steps":["trace[1266677643] 'agreement among raft nodes before linearized reading' (duration: 100.920813ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:41:05.588026Z","caller":"traceutil/trace.go:171","msg":"trace[2088366092] linearizableReadLoop","detail":"{readStateIndex:1978; appliedIndex:1977; }","duration":"356.740553ms","start":"2026-02-24T04:41:05.231264Z","end":"2026-02-24T04:41:05.588005Z","steps":["trace[2088366092] 'read index received' (duration: 356.701262ms)","trace[2088366092] 'applied index is now lower than readState.Index' (duration: 38.451µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:41:05.588154Z","caller":"traceutil/trace.go:171","msg":"trace[1342721219] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"723.91789ms","start":"2026-02-24T04:41:04.864224Z","end":"2026-02-24T04:41:05.588142Z","steps":["trace[1342721219] 'process raft request' (duration: 723.654484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:41:05.588252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:41:04.864199Z","time spent":"723.977022ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:41:05.588285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.699719ms","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":"warn","ts":"2026-02-24T04:41:05.588314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.331917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:41:05.588374Z","caller":"traceutil/trace.go:171","msg":"trace[1746943326] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1888; }","duration":"256.844812ms","start":"2026-02-24T04:41:05.331506Z","end":"2026-02-24T04:41:05.588351Z","steps":["trace[1746943326] 'agreement among raft nodes before linearized reading' (duration: 256.689268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:41:05.588447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.424601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:41:05.58854Z","caller":"traceutil/trace.go:171","msg":"trace[711590030] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1888; }","duration":"179.584515ms","start":"2026-02-24T04:41:05.408934Z","end":"2026-02-24T04:41:05.588518Z","steps":["trace[711590030] 'agreement among raft nodes before linearized reading' (duration: 179.418141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:41:05.588598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.093387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-24T04:41:05.588657Z","caller":"traceutil/trace.go:171","msg":"trace[645465496] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1888; }","duration":"183.156459ms","start":"2026-02-24T04:41:05.405486Z","end":"2026-02-24T04:41:05.588642Z","steps":["trace[645465496] 'agreement among raft nodes before linearized reading' (duration: 182.905143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:41:05.58871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.434479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T04:41:05.588774Z","caller":"traceutil/trace.go:171","msg":"trace[1851722346] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1888; }","duration":"357.49113ms","start":"2026-02-24T04:41:05.231257Z","end":"2026-02-24T04:41:05.588748Z","steps":["trace[1851722346] 'agreement among raft nodes before linearized reading' (duration: 357.216584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:41:05.58882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:41:05.231237Z","time spent":"357.573402ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T04:41:05.588976Z","caller":"traceutil/trace.go:171","msg":"trace[74940350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1888; }","duration":"153.456509ms","start":"2026-02-24T04:41:05.434924Z","end":"2026-02-24T04:41:05.588381Z","steps":["trace[74940350] 'agreement among raft nodes before linearized reading' (duration: 153.295346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:41:07.006521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.518042ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T04:41:07.006656Z","caller":"traceutil/trace.go:171","msg":"trace[1571864659] linearizableReadLoop","detail":"{readStateIndex:1983; appliedIndex:1982; }","duration":"374.879379ms","start":"2026-02-24T04:41:06.631754Z","end":"2026-02-24T04:41:07.006633Z","steps":["trace[1571864659] 'read index received' (duration: 59.234103ms)","trace[1571864659] 'applied index is now lower than readState.Index' (duration: 315.643766ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:41:07.006867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.309261ms","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:533"} {"level":"info","ts":"2026-02-24T04:41:07.006908Z","caller":"traceutil/trace.go:171","msg":"trace[1184711871] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1892; }","duration":"140.393243ms","start":"2026-02-24T04:41:06.8665Z","end":"2026-02-24T04:41:07.006893Z","steps":["trace[1184711871] 'agreement among raft nodes before linearized reading' (duration: 140.231209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:41:07.006911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.136675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T04:41:07.007005Z","caller":"traceutil/trace.go:171","msg":"trace[2077288992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1892; }","duration":"375.264758ms","start":"2026-02-24T04:41:06.631718Z","end":"2026-02-24T04:41:07.006983Z","steps":["trace[2077288992] 'agreement among raft nodes before linearized reading' (duration: 374.982151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:41:07.007064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:41:06.631695Z","time spent":"375.356561ms","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T04:41:07.119732Z","caller":"traceutil/trace.go:171","msg":"trace[1094126616] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"107.635092ms","start":"2026-02-24T04:41:07.012046Z","end":"2026-02-24T04:41:07.119681Z","steps":["trace[1094126616] 'process raft request' (duration: 107.225943ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:41:07.145739Z","caller":"traceutil/trace.go:171","msg":"trace[134261454] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"103.490914ms","start":"2026-02-24T04:41:07.042216Z","end":"2026-02-24T04:41:07.145707Z","steps":["trace[134261454] 'process raft request' (duration: 103.256889ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:41:09.812325Z","caller":"traceutil/trace.go:171","msg":"trace[1007963081] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"119.713496ms","start":"2026-02-24T04:41:09.692582Z","end":"2026-02-24T04:41:09.812296Z","steps":["trace[1007963081] 'process raft request' (duration: 100.409542ms)","trace[1007963081] 'compare' (duration: 19.12681ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:41:09.812636Z","caller":"traceutil/trace.go:171","msg":"trace[552880193] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"117.870743ms","start":"2026-02-24T04:41:09.694592Z","end":"2026-02-24T04:41:09.812462Z","steps":["trace[552880193] 'process raft request' (duration: 117.661028ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:41:13.46334Z","caller":"traceutil/trace.go:171","msg":"trace[1699432321] linearizableReadLoop","detail":"{readStateIndex:2017; appliedIndex:2016; }","duration":"104.98488ms","start":"2026-02-24T04:41:13.358311Z","end":"2026-02-24T04:41:13.463296Z","steps":["trace[1699432321] 'read index received' (duration: 104.853837ms)","trace[1699432321] 'applied index is now lower than readState.Index' (duration: 129.823µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:41:13.463378Z","caller":"traceutil/trace.go:171","msg":"trace[1718908198] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"115.268441ms","start":"2026-02-24T04:41:13.348063Z","end":"2026-02-24T04:41:13.463331Z","steps":["trace[1718908198] 'process raft request' (duration: 115.094917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:41:13.463518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.185224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient\" ","response":"range_response_count:1 size:706"} {"level":"info","ts":"2026-02-24T04:41:13.4636Z","caller":"traceutil/trace.go:171","msg":"trace[919777535] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient; range_end:; response_count:1; response_revision:1925; }","duration":"105.314667ms","start":"2026-02-24T04:41:13.358264Z","end":"2026-02-24T04:41:13.463579Z","steps":["trace[919777535] 'agreement among raft nodes before linearized reading' (duration: 105.152984ms)"],"step_count":1}