{"level":"warn","ts":"2026-02-19T02:26:58.64249Z","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-19T02:26:58.642581Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222: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.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222: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-19T02:26:58.642664Z","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-19T02:26:58.642673Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-02-19T02:26:58.642691Z","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-19T02:26:58.643335Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-02-19T02:26:58.643437Z","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.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222: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-19T02:26:58.650404Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.429405ms"} {"level":"info","ts":"2026-02-19T02:26:58.661482Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-02-19T02:26:58.661568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-02-19T02:26:58.661591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-02-19T02:26:58.661615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-19T02:26:58.661638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-02-19T02:26:58.661685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-02-19T02:26:58.67146Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-19T02:26:58.677903Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-19T02:26:58.679786Z","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-19T02:26:58.683222Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-19T02:26:58.683583Z","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-19T02:26:58.683653Z","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-19T02:26:58.683664Z","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-19T02:26:58.683794Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-19T02:26:58.684901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-02-19T02:26:58.685145Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-02-19T02:26:58.68736Z","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-19T02:26:58.687626Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-19T02:26:58.687657Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-19T02:26:58.687813Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-02-19T02:26:58.687828Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-02-19T02:26:58.962364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-02-19T02:26:58.962458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-02-19T02:26:58.962489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-02-19T02:26:58.962509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-02-19T02:26:58.962521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-02-19T02:26:58.96254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-02-19T02:26:58.962554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-02-19T02:26:58.96556Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:26:58.967508Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T02:26:58.967516Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T02:26:58.967509Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-02-19T02:26:58.967873Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-19T02:26:58.967891Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-19T02:26:58.970703Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"info","ts":"2026-02-19T02:26:58.972651Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-19T02:26:58.974463Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:26:58.974653Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:26:58.974687Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:27:02.715804Z","caller":"traceutil/trace.go:171","msg":"trace[635762942] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"118.21049ms","start":"2026-02-19T02:27:02.597565Z","end":"2026-02-19T02:27:02.715775Z","steps":["trace[635762942] 'process raft request' (duration: 118.044057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:02.879222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.41425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:27:02.87939Z","caller":"traceutil/trace.go:171","msg":"trace[1112910356] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"154.359552ms","start":"2026-02-19T02:27:02.725005Z","end":"2026-02-19T02:27:02.879364Z","steps":["trace[1112910356] 'process raft request' (duration: 51.04187ms)","trace[1112910356] 'compare' (duration: 102.214565ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:27:06.503665Z","caller":"traceutil/trace.go:171","msg":"trace[2135453794] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"217.981076ms","start":"2026-02-19T02:27:06.285654Z","end":"2026-02-19T02:27:06.503635Z","steps":["trace[2135453794] 'process raft request' (duration: 217.828832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:06.974132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.387625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:27:06.974217Z","caller":"traceutil/trace.go:171","msg":"trace[771495440] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:0; response_revision:330; }","duration":"288.531588ms","start":"2026-02-19T02:27:06.685668Z","end":"2026-02-19T02:27:06.974199Z","steps":["trace[771495440] 'range keys from in-memory index tree' (duration: 288.320673ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:07.140299Z","caller":"traceutil/trace.go:171","msg":"trace[1829915747] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"121.737556ms","start":"2026-02-19T02:27:07.018537Z","end":"2026-02-19T02:27:07.140275Z","steps":["trace[1829915747] 'process raft request' (duration: 65.573052ms)","trace[1829915747] 'compare' (duration: 56.071752ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:27:07.588184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.526867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:27:07.588283Z","caller":"traceutil/trace.go:171","msg":"trace[1876329772] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:0; response_revision:334; }","duration":"103.689991ms","start":"2026-02-19T02:27:07.484573Z","end":"2026-02-19T02:27:07.588263Z","steps":["trace[1876329772] 'range keys from in-memory index tree' (duration: 103.380333ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:08.69778Z","caller":"traceutil/trace.go:171","msg":"trace[596692623] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"102.358299ms","start":"2026-02-19T02:27:08.595404Z","end":"2026-02-19T02:27:08.697763Z","steps":["trace[596692623] 'process raft request' (duration: 101.643931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:08.864101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.398689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:27:08.865193Z","caller":"traceutil/trace.go:171","msg":"trace[2007262960] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"162.199951ms","start":"2026-02-19T02:27:08.702968Z","end":"2026-02-19T02:27:08.865168Z","steps":["trace[2007262960] 'process raft request' (duration: 24.450079ms)","trace[2007262960] 'compare' (duration: 136.241666ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:27:09.088738Z","caller":"traceutil/trace.go:171","msg":"trace[1531701237] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:358; }","duration":"353.501105ms","start":"2026-02-19T02:27:08.735208Z","end":"2026-02-19T02:27:09.088709Z","steps":["trace[1531701237] 'read index received' (duration: 331.930565ms)","trace[1531701237] 'applied index is now lower than readState.Index' (duration: 21.56915ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:27:09.088862Z","caller":"traceutil/trace.go:171","msg":"trace[268914467] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"382.286808ms","start":"2026-02-19T02:27:08.706539Z","end":"2026-02-19T02:27:09.088826Z","steps":["trace[268914467] 'process raft request' (duration: 360.627317ms)","trace[268914467] 'compare' (duration: 21.416706ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:27:09.089084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.862674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-02-19T02:27:09.089082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:08.706523Z","time spent":"382.422242ms","remote":"127.0.0.1:53804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:27:09.089171Z","caller":"traceutil/trace.go:171","msg":"trace[255238022] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:348; }","duration":"354.007197ms","start":"2026-02-19T02:27:08.735149Z","end":"2026-02-19T02:27:09.089156Z","steps":["trace[255238022] 'agreement among raft nodes before linearized reading' (duration: 353.723211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:09.08924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:08.735125Z","time spent":"354.102689ms","remote":"127.0.0.1:53832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-02-19T02:27:09.08925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.405023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-19T02:27:09.08936Z","caller":"traceutil/trace.go:171","msg":"trace[1633009132] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:348; }","duration":"302.555237ms","start":"2026-02-19T02:27:08.78678Z","end":"2026-02-19T02:27:09.089335Z","steps":["trace[1633009132] 'agreement among raft nodes before linearized reading' (duration: 302.352722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:09.089414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:08.786765Z","time spent":"302.639618ms","remote":"127.0.0.1:53832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-02-19T02:27:09.089592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.382038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-02-19T02:27:09.089641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.703067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-19T02:27:09.089688Z","caller":"traceutil/trace.go:171","msg":"trace[832411933] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:348; }","duration":"153.79831ms","start":"2026-02-19T02:27:08.935877Z","end":"2026-02-19T02:27:09.089676Z","steps":["trace[832411933] 'agreement among raft nodes before linearized reading' (duration: 153.657036ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:09.089646Z","caller":"traceutil/trace.go:171","msg":"trace[109569339] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:348; }","duration":"104.497401ms","start":"2026-02-19T02:27:08.985134Z","end":"2026-02-19T02:27:09.089632Z","steps":["trace[109569339] 'agreement among raft nodes before linearized reading' (duration: 104.361907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:09.089874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.471581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-19T02:27:09.08991Z","caller":"traceutil/trace.go:171","msg":"trace[1882192466] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:348; }","duration":"204.570673ms","start":"2026-02-19T02:27:08.885331Z","end":"2026-02-19T02:27:09.089902Z","steps":["trace[1882192466] 'agreement among raft nodes before linearized reading' (duration: 204.506341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:09.090089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.498564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-19T02:27:09.090145Z","caller":"traceutil/trace.go:171","msg":"trace[1654492356] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:348; }","duration":"253.556466ms","start":"2026-02-19T02:27:08.836577Z","end":"2026-02-19T02:27:09.090134Z","steps":["trace[1654492356] 'agreement among raft nodes before linearized reading' (duration: 253.466453ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:09.402942Z","caller":"traceutil/trace.go:171","msg":"trace[286320464] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"301.303416ms","start":"2026-02-19T02:27:09.101602Z","end":"2026-02-19T02:27:09.402905Z","steps":["trace[286320464] 'process raft request' (duration: 243.776259ms)","trace[286320464] 'compare' (duration: 57.199669ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:27:09.403184Z","caller":"traceutil/trace.go:171","msg":"trace[643386634] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"301.390877ms","start":"2026-02-19T02:27:09.101757Z","end":"2026-02-19T02:27:09.403148Z","steps":["trace[643386634] 'process raft request' (duration: 301.010438ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:09.403403Z","caller":"traceutil/trace.go:171","msg":"trace[1936981564] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"300.464377ms","start":"2026-02-19T02:27:09.102922Z","end":"2026-02-19T02:27:09.403386Z","steps":["trace[1936981564] 'process raft request' (duration: 300.022645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:09.403554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:09.102915Z","time spent":"300.561289ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:27:09.403232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:09.101585Z","time spent":"301.544561ms","remote":"127.0.0.1:53896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T02:27:09.404222Z","caller":"traceutil/trace.go:171","msg":"trace[706068742] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"299.452851ms","start":"2026-02-19T02:27:09.104751Z","end":"2026-02-19T02:27:09.404204Z","steps":["trace[706068742] 'process raft request' (duration: 298.358384ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:09.404132Z","caller":"traceutil/trace.go:171","msg":"trace[791391802] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"300.713233ms","start":"2026-02-19T02:27:09.103382Z","end":"2026-02-19T02:27:09.404095Z","steps":["trace[791391802] 'process raft request' (duration: 299.676807ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:09.403272Z","caller":"traceutil/trace.go:171","msg":"trace[863052856] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"300.752083ms","start":"2026-02-19T02:27:09.102461Z","end":"2026-02-19T02:27:09.403213Z","steps":["trace[863052856] 'process raft request' (duration: 300.426355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:09.40448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:09.101739Z","time spent":"301.730487ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T02:27:09.404531Z","caller":"traceutil/trace.go:171","msg":"trace[931348020] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"299.747749ms","start":"2026-02-19T02:27:09.104769Z","end":"2026-02-19T02:27:09.404516Z","steps":["trace[931348020] 'process raft request' (duration: 299.320749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:09.404483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:09.102454Z","time spent":"301.956832ms","remote":"127.0.0.1:53794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T02:27:09.403327Z","caller":"traceutil/trace.go:171","msg":"trace[436487988] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"301.010249ms","start":"2026-02-19T02:27:09.1023Z","end":"2026-02-19T02:27:09.40331Z","steps":["trace[436487988] 'process raft request' (duration: 300.540297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:09.404737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:09.102279Z","time spent":"302.432933ms","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2125,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T02:27:09.404553Z","caller":"traceutil/trace.go:171","msg":"trace[607992142] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"298.98584ms","start":"2026-02-19T02:27:09.105549Z","end":"2026-02-19T02:27:09.404535Z","steps":["trace[607992142] 'process raft request' (duration: 298.618101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:09.404505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:09.103372Z","time spent":"301.02897ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:27:09.937129Z","caller":"traceutil/trace.go:171","msg":"trace[471598225] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"523.615537ms","start":"2026-02-19T02:27:09.413482Z","end":"2026-02-19T02:27:09.937098Z","steps":["trace[471598225] 'process raft request' (duration: 462.774419ms)","trace[471598225] 'compare' (duration: 60.686404ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:27:09.9373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:09.413463Z","time spent":"523.744321ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:27:09.989414Z","caller":"traceutil/trace.go:171","msg":"trace[94812685] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:369; }","duration":"575.679993ms","start":"2026-02-19T02:27:09.413709Z","end":"2026-02-19T02:27:09.989389Z","steps":["trace[94812685] 'read index received' (duration: 462.490393ms)","trace[94812685] 'applied index is now lower than readState.Index' (duration: 113.18806ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:27:09.990351Z","caller":"traceutil/trace.go:171","msg":"trace[1090900143] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"576.776229ms","start":"2026-02-19T02:27:09.413555Z","end":"2026-02-19T02:27:09.990331Z","steps":["trace[1090900143] 'process raft request' (duration: 575.480238ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:09.990381Z","caller":"traceutil/trace.go:171","msg":"trace[1631398813] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"576.152985ms","start":"2026-02-19T02:27:09.413498Z","end":"2026-02-19T02:27:09.989651Z","steps":["trace[1631398813] 'process raft request' (duration: 575.417667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:09.990433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:09.413532Z","time spent":"576.856541ms","remote":"127.0.0.1:54066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:27:09.990509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:09.413472Z","time spent":"576.964243ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T02:27:09.990575Z","caller":"traceutil/trace.go:171","msg":"trace[1139271745] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"576.697517ms","start":"2026-02-19T02:27:09.41387Z","end":"2026-02-19T02:27:09.990568Z","steps":["trace[1139271745] 'process raft request' (duration: 575.293534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:09.990605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:09.413852Z","time spent":"576.739088ms","remote":"127.0.0.1:53814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3355,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T02:27:09.99067Z","caller":"traceutil/trace.go:171","msg":"trace[268433258] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"576.513643ms","start":"2026-02-19T02:27:09.41415Z","end":"2026-02-19T02:27:09.990664Z","steps":["trace[268433258] 'process raft request' (duration: 575.077418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:09.990702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:09.414143Z","time spent":"576.542883ms","remote":"127.0.0.1:53702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T02:27:09.99101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.301192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-19T02:27:09.991036Z","caller":"traceutil/trace.go:171","msg":"trace[735871499] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:364; }","duration":"577.381613ms","start":"2026-02-19T02:27:09.413648Z","end":"2026-02-19T02:27:09.991029Z","steps":["trace[735871499] 'agreement among raft nodes before linearized reading' (duration: 577.332192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:09.991055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:09.41363Z","time spent":"577.421085ms","remote":"127.0.0.1:53832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-02-19T02:27:09.990791Z","caller":"traceutil/trace.go:171","msg":"trace[1586626143] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"576.267206ms","start":"2026-02-19T02:27:09.414507Z","end":"2026-02-19T02:27:09.990774Z","steps":["trace[1586626143] 'process raft request' (duration: 574.823492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:09.992539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.022244ms","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-19T02:27:09.992632Z","caller":"traceutil/trace.go:171","msg":"trace[423092580] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:364; }","duration":"115.197288ms","start":"2026-02-19T02:27:09.877412Z","end":"2026-02-19T02:27:09.992609Z","steps":["trace[423092580] 'agreement among raft nodes before linearized reading' (duration: 113.596489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:09.992621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:09.414498Z","time spent":"577.972967ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:27:10.407545Z","caller":"traceutil/trace.go:171","msg":"trace[1862882029] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"409.118026ms","start":"2026-02-19T02:27:09.998383Z","end":"2026-02-19T02:27:10.407501Z","steps":["trace[1862882029] 'process raft request' (duration: 395.574399ms)","trace[1862882029] 'compare' (duration: 13.154978ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:27:10.407716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:09.998373Z","time spent":"409.257209ms","remote":"127.0.0.1:53882","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-19T02:27:10.408016Z","caller":"traceutil/trace.go:171","msg":"trace[415072121] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"328.40549ms","start":"2026-02-19T02:27:10.079592Z","end":"2026-02-19T02:27:10.407997Z","steps":["trace[415072121] 'process raft request' (duration: 328.369849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:10.408193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:10.079562Z","time spent":"328.506061ms","remote":"127.0.0.1:53702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T02:27:10.408331Z","caller":"traceutil/trace.go:171","msg":"trace[1856194705] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:375; }","duration":"409.3186ms","start":"2026-02-19T02:27:09.998993Z","end":"2026-02-19T02:27:10.408312Z","steps":["trace[1856194705] 'read index received' (duration: 73.278336ms)","trace[1856194705] 'applied index is now lower than readState.Index' (duration: 336.038694ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:27:10.409082Z","caller":"traceutil/trace.go:171","msg":"trace[1254574037] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"410.625192ms","start":"2026-02-19T02:27:09.998375Z","end":"2026-02-19T02:27:10.409Z","steps":["trace[1254574037] 'process raft request' (duration: 409.074545ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:10.40921Z","caller":"traceutil/trace.go:171","msg":"trace[1517501451] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"410.327906ms","start":"2026-02-19T02:27:09.998851Z","end":"2026-02-19T02:27:10.409179Z","steps":["trace[1517501451] 'process raft request' (duration: 409.038594ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:10.409315Z","caller":"traceutil/trace.go:171","msg":"trace[1688636793] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"410.808387ms","start":"2026-02-19T02:27:09.998465Z","end":"2026-02-19T02:27:10.409273Z","steps":["trace[1688636793] 'process raft request' (duration: 409.184007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:10.40937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:09.998843Z","time spent":"410.450698ms","remote":"127.0.0.1:54082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2864,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:27:10.409386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:09.99769Z","time spent":"411.495963ms","remote":"127.0.0.1:53702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T02:27:10.409416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:09.998457Z","time spent":"410.919269ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T02:27:10.409517Z","caller":"traceutil/trace.go:171","msg":"trace[1453494558] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"407.084427ms","start":"2026-02-19T02:27:10.002416Z","end":"2026-02-19T02:27:10.4095Z","steps":["trace[1453494558] 'process raft request' (duration: 405.507429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:10.409617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:10.002406Z","time spent":"407.164959ms","remote":"127.0.0.1:53814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3505,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T02:27:10.409549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.490418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"warn","ts":"2026-02-19T02:27:10.409681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.550981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-dcnb2\" ","response":"range_response_count:1 size:3416"} {"level":"warn","ts":"2026-02-19T02:27:10.409675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.674098ms","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-19T02:27:10.410721Z","caller":"traceutil/trace.go:171","msg":"trace[1937560473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:372; }","duration":"366.800896ms","start":"2026-02-19T02:27:10.043905Z","end":"2026-02-19T02:27:10.410706Z","steps":["trace[1937560473] 'agreement among raft nodes before linearized reading' (duration: 365.440774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:10.41078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:10.04388Z","time spent":"366.885957ms","remote":"127.0.0.1:53882","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-19T02:27:10.409686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.731488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-19T02:27:10.410946Z","caller":"traceutil/trace.go:171","msg":"trace[364390527] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:372; }","duration":"148.032679ms","start":"2026-02-19T02:27:10.262904Z","end":"2026-02-19T02:27:10.410936Z","steps":["trace[364390527] 'agreement among raft nodes before linearized reading' (duration: 146.740569ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:10.409709Z","caller":"traceutil/trace.go:171","msg":"trace[586544027] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-dcnb2; range_end:; response_count:1; response_revision:372; }","duration":"330.712035ms","start":"2026-02-19T02:27:10.078987Z","end":"2026-02-19T02:27:10.409699Z","steps":["trace[586544027] 'agreement among raft nodes before linearized reading' (duration: 330.670114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:10.411063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:10.078973Z","time spent":"332.076767ms","remote":"127.0.0.1:53814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":3439,"request content":"key:\"/registry/pods/kube-system/kube-proxy-dcnb2\" "} {"level":"warn","ts":"2026-02-19T02:27:10.409627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.72173ms","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-19T02:27:10.411151Z","caller":"traceutil/trace.go:171","msg":"trace[248730192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:372; }","duration":"321.267666ms","start":"2026-02-19T02:27:10.08987Z","end":"2026-02-19T02:27:10.411138Z","steps":["trace[248730192] 'agreement among raft nodes before linearized reading' (duration: 319.721079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:10.411191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:10.089859Z","time spent":"321.321418ms","remote":"127.0.0.1:53882","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":"info","ts":"2026-02-19T02:27:10.409713Z","caller":"traceutil/trace.go:171","msg":"trace[1789578015] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:372; }","duration":"192.057802ms","start":"2026-02-19T02:27:10.217638Z","end":"2026-02-19T02:27:10.409696Z","steps":["trace[1789578015] 'agreement among raft nodes before linearized reading' (duration: 191.476288ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:10.408045Z","caller":"traceutil/trace.go:171","msg":"trace[1730000757] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"409.554446ms","start":"2026-02-19T02:27:09.998457Z","end":"2026-02-19T02:27:10.408011Z","steps":["trace[1730000757] 'process raft request' (duration: 409.246489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:10.411373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:09.99844Z","time spent":"412.906107ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:27:10.410425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.431672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-02-19T02:27:10.411529Z","caller":"traceutil/trace.go:171","msg":"trace[688516250] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:372; }","duration":"412.602189ms","start":"2026-02-19T02:27:09.998919Z","end":"2026-02-19T02:27:10.411521Z","steps":["trace[688516250] 'agreement among raft nodes before linearized reading' (duration: 410.302225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:10.411555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:09.99891Z","time spent":"412.63797ms","remote":"127.0.0.1:54066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":3707,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-19T02:27:17.708128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.779035ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:27:17.708359Z","caller":"traceutil/trace.go:171","msg":"trace[2025225107] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"231.79097ms","start":"2026-02-19T02:27:17.476542Z","end":"2026-02-19T02:27:17.708333Z","steps":["trace[2025225107] 'process raft request' (duration: 61.666827ms)","trace[2025225107] 'compare' (duration: 169.58139ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:27:17.919608Z","caller":"traceutil/trace.go:171","msg":"trace[293544687] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"125.126868ms","start":"2026-02-19T02:27:17.794453Z","end":"2026-02-19T02:27:17.91958Z","steps":["trace[293544687] 'process raft request' (duration: 91.777634ms)","trace[293544687] 'compare' (duration: 33.252791ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:27:24.97676Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890116500759505129,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:27:25.217029Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.001876621s","expected-duration":"1s"} {"level":"info","ts":"2026-02-19T02:27:25.21756Z","caller":"traceutil/trace.go:171","msg":"trace[1989845751] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:478; }","duration":"740.831956ms","start":"2026-02-19T02:27:24.476697Z","end":"2026-02-19T02:27:25.217529Z","steps":["trace[1989845751] 'read index received' (duration: 740.521448ms)","trace[1989845751] 'applied index is now lower than readState.Index' (duration: 306.728µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:27:25.217689Z","caller":"traceutil/trace.go:171","msg":"trace[1821632243] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"1.002641639s","start":"2026-02-19T02:27:24.215029Z","end":"2026-02-19T02:27:25.21767Z","steps":["trace[1821632243] 'process raft request' (duration: 1.002294171s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:25.217816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:24.215Z","time spent":"1.002725791s","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:27:25.217905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.028426ms","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-19T02:27:25.217963Z","caller":"traceutil/trace.go:171","msg":"trace[1920189565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:463; }","duration":"638.120979ms","start":"2026-02-19T02:27:24.579823Z","end":"2026-02-19T02:27:25.217944Z","steps":["trace[1920189565] 'agreement among raft nodes before linearized reading' (duration: 638.023156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:25.21808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:24.579801Z","time spent":"638.263992ms","remote":"127.0.0.1:53882","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-19T02:27:25.218131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.5494ms","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-19T02:27:25.218208Z","caller":"traceutil/trace.go:171","msg":"trace[1322475422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:463; }","duration":"619.660333ms","start":"2026-02-19T02:27:24.598526Z","end":"2026-02-19T02:27:25.218186Z","steps":["trace[1322475422] 'agreement among raft nodes before linearized reading' (duration: 619.488619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:25.218253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:24.598515Z","time spent":"619.728295ms","remote":"127.0.0.1:53882","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-19T02:27:25.218248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.541223ms","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-19T02:27:25.218279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.415073ms","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-19T02:27:25.218308Z","caller":"traceutil/trace.go:171","msg":"trace[532290058] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:463; }","duration":"741.651176ms","start":"2026-02-19T02:27:24.47664Z","end":"2026-02-19T02:27:25.218291Z","steps":["trace[532290058] 'agreement among raft nodes before linearized reading' (duration: 741.536863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:25.218396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:24.47662Z","time spent":"741.762708ms","remote":"127.0.0.1:53882","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-19T02:27:25.218413Z","caller":"traceutil/trace.go:171","msg":"trace[2110708553] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:463; }","duration":"635.630179ms","start":"2026-02-19T02:27:24.58274Z","end":"2026-02-19T02:27:25.21837Z","steps":["trace[2110708553] 'agreement among raft nodes before linearized reading' (duration: 635.332682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:25.218485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:24.582695Z","time spent":"635.779722ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-19T02:27:25.597299Z","caller":"traceutil/trace.go:171","msg":"trace[1680559410] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"374.790539ms","start":"2026-02-19T02:27:25.222484Z","end":"2026-02-19T02:27:25.597274Z","steps":["trace[1680559410] 'process raft request' (duration: 310.453537ms)","trace[1680559410] 'compare' (duration: 63.981393ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:27:25.597422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:25.222454Z","time spent":"374.897462ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:27:25.604127Z","caller":"traceutil/trace.go:171","msg":"trace[1574494634] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"381.022819ms","start":"2026-02-19T02:27:25.223083Z","end":"2026-02-19T02:27:25.604106Z","steps":["trace[1574494634] 'process raft request' (duration: 380.831874ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:25.604369Z","caller":"traceutil/trace.go:171","msg":"trace[377586674] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"381.146912ms","start":"2026-02-19T02:27:25.223183Z","end":"2026-02-19T02:27:25.60433Z","steps":["trace[377586674] 'process raft request' (duration: 380.879056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:25.604477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:25.22317Z","time spent":"381.250484ms","remote":"127.0.0.1:53882","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-19T02:27:25.604547Z","caller":"traceutil/trace.go:171","msg":"trace[1947336009] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:479; }","duration":"330.567712ms","start":"2026-02-19T02:27:25.273957Z","end":"2026-02-19T02:27:25.604525Z","steps":["trace[1947336009] 'read index received' (duration: 258.968185ms)","trace[1947336009] 'applied index is now lower than readState.Index' (duration: 71.598387ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:27:25.604637Z","caller":"traceutil/trace.go:171","msg":"trace[48382334] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"381.393487ms","start":"2026-02-19T02:27:25.223193Z","end":"2026-02-19T02:27:25.604587Z","steps":["trace[48382334] 'process raft request' (duration: 381.07561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:25.604657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.676884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:27:25.604725Z","caller":"traceutil/trace.go:171","msg":"trace[1546406099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:467; }","duration":"330.796027ms","start":"2026-02-19T02:27:25.273912Z","end":"2026-02-19T02:27:25.604708Z","steps":["trace[1546406099] 'agreement among raft nodes before linearized reading' (duration: 330.696055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:25.604778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:25.273891Z","time spent":"330.872869ms","remote":"127.0.0.1:53650","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-19T02:27:25.60484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:25.223177Z","time spent":"381.516001ms","remote":"127.0.0.1:53882","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-19T02:27:25.604875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:25.223062Z","time spent":"381.199554ms","remote":"127.0.0.1:53882","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-19T02:27:27.782401Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890116500759505141,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:27:27.941933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.339283ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-19T02:27:27.942048Z","caller":"traceutil/trace.go:171","msg":"trace[1492452798] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:483; }","duration":"660.367511ms","start":"2026-02-19T02:27:27.28165Z","end":"2026-02-19T02:27:27.942018Z","steps":["trace[1492452798] 'read index received' (duration: 545.943356ms)","trace[1492452798] 'applied index is now lower than readState.Index' (duration: 114.423035ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:27:27.942172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.383661ms","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-19T02:27:27.942196Z","caller":"traceutil/trace.go:171","msg":"trace[134722066] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:467; }","duration":"341.451072ms","start":"2026-02-19T02:27:27.600735Z","end":"2026-02-19T02:27:27.942186Z","steps":["trace[134722066] 'agreement among raft nodes before linearized reading' (duration: 341.387961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:27.942223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:27.600716Z","time spent":"341.501543ms","remote":"127.0.0.1:53882","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-19T02:27:27.942246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.532065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:27:27.942337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.750006ms","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-19T02:27:27.942286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.517741ms","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-19T02:27:27.942402Z","caller":"traceutil/trace.go:171","msg":"trace[2130770856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:467; }","duration":"660.74534ms","start":"2026-02-19T02:27:27.281621Z","end":"2026-02-19T02:27:27.942366Z","steps":["trace[2130770856] 'agreement among raft nodes before linearized reading' (duration: 660.480633ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:27.942421Z","caller":"traceutil/trace.go:171","msg":"trace[408245141] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:467; }","duration":"333.89079ms","start":"2026-02-19T02:27:27.608513Z","end":"2026-02-19T02:27:27.942404Z","steps":["trace[408245141] 'agreement among raft nodes before linearized reading' (duration: 333.726406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:27.942481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:27.608492Z","time spent":"333.981172ms","remote":"127.0.0.1:53882","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-19T02:27:27.94248Z","caller":"traceutil/trace.go:171","msg":"trace[70019332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:467; }","duration":"333.737447ms","start":"2026-02-19T02:27:27.60871Z","end":"2026-02-19T02:27:27.942447Z","steps":["trace[70019332] 'agreement among raft nodes before linearized reading' (duration: 333.45866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:27.942484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:27.281607Z","time spent":"660.863524ms","remote":"127.0.0.1:53650","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-19T02:27:27.942533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:27.608702Z","time spent":"333.820738ms","remote":"127.0.0.1:53882","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-19T02:27:27.942414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.87139ms","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-19T02:27:27.942677Z","caller":"traceutil/trace.go:171","msg":"trace[1482508792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:467; }","duration":"334.158526ms","start":"2026-02-19T02:27:27.608511Z","end":"2026-02-19T02:27:27.94267Z","steps":["trace[1482508792] 'agreement among raft nodes before linearized reading' (duration: 333.842248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:27.942719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:27.608496Z","time spent":"334.212018ms","remote":"127.0.0.1:53882","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-19T02:27:30.095017Z","caller":"traceutil/trace.go:171","msg":"trace[1338939319] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"138.814044ms","start":"2026-02-19T02:27:29.956091Z","end":"2026-02-19T02:27:30.094905Z","steps":["trace[1338939319] 'process raft request' (duration: 138.569078ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:30.097815Z","caller":"traceutil/trace.go:171","msg":"trace[1201284541] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"141.058558ms","start":"2026-02-19T02:27:29.956728Z","end":"2026-02-19T02:27:30.097786Z","steps":["trace[1201284541] 'process raft request' (duration: 140.969206ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:30.097897Z","caller":"traceutil/trace.go:171","msg":"trace[1738651868] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"141.726174ms","start":"2026-02-19T02:27:29.956118Z","end":"2026-02-19T02:27:30.097844Z","steps":["trace[1738651868] 'process raft request' (duration: 141.460487ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:30.098219Z","caller":"traceutil/trace.go:171","msg":"trace[907269487] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"141.010105ms","start":"2026-02-19T02:27:29.957194Z","end":"2026-02-19T02:27:30.098204Z","steps":["trace[907269487] 'process raft request' (duration: 140.542854ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:30.098135Z","caller":"traceutil/trace.go:171","msg":"trace[1899054736] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:522; }","duration":"120.587344ms","start":"2026-02-19T02:27:29.977526Z","end":"2026-02-19T02:27:30.098113Z","steps":["trace[1899054736] 'read index received' (duration: 117.09007ms)","trace[1899054736] 'applied index is now lower than readState.Index' (duration: 3.496184ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:27:30.09844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.885081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4021"} {"level":"info","ts":"2026-02-19T02:27:30.098501Z","caller":"traceutil/trace.go:171","msg":"trace[68845344] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:509; }","duration":"121.028875ms","start":"2026-02-19T02:27:29.977456Z","end":"2026-02-19T02:27:30.098485Z","steps":["trace[68845344] 'agreement among raft nodes before linearized reading' (duration: 120.879881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:32.599903Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890116500759505264,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:27:32.934052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:32.079552Z","time spent":"854.496366ms","remote":"127.0.0.1:53656","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-19T02:27:33.07484Z","caller":"traceutil/trace.go:171","msg":"trace[101856019] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"894.969881ms","start":"2026-02-19T02:27:32.179846Z","end":"2026-02-19T02:27:33.074816Z","steps":["trace[101856019] 'process raft request' (duration: 894.676894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:33.074963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:32.179823Z","time spent":"895.082284ms","remote":"127.0.0.1:53678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T02:27:33.075089Z","caller":"traceutil/trace.go:171","msg":"trace[794729682] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"693.243754ms","start":"2026-02-19T02:27:32.381825Z","end":"2026-02-19T02:27:33.075069Z","steps":["trace[794729682] 'process raft request' (duration: 692.825383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:33.075215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:32.381806Z","time spent":"693.334506ms","remote":"127.0.0.1:53678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T02:27:33.075699Z","caller":"traceutil/trace.go:171","msg":"trace[552272265] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"140.315618ms","start":"2026-02-19T02:27:32.935369Z","end":"2026-02-19T02:27:33.075685Z","steps":["trace[552272265] 'process raft request' (duration: 140.256407ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:33.075953Z","caller":"traceutil/trace.go:171","msg":"trace[559760099] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"491.999638ms","start":"2026-02-19T02:27:32.583943Z","end":"2026-02-19T02:27:33.075942Z","steps":["trace[559760099] 'process raft request' (duration: 490.824259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:33.076019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:32.58392Z","time spent":"492.06639ms","remote":"127.0.0.1:53678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T02:27:33.076144Z","caller":"traceutil/trace.go:171","msg":"trace[1488190952] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:558; }","duration":"977.100699ms","start":"2026-02-19T02:27:32.099034Z","end":"2026-02-19T02:27:33.076134Z","steps":["trace[1488190952] 'read index received' (duration: 834.988437ms)","trace[1488190952] 'applied index is now lower than readState.Index' (duration: 142.110992ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:27:33.076335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.292143ms","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-19T02:27:33.076377Z","caller":"traceutil/trace.go:171","msg":"trace[600787547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:544; }","duration":"977.376306ms","start":"2026-02-19T02:27:32.098991Z","end":"2026-02-19T02:27:33.076367Z","steps":["trace[600787547] 'agreement among raft nodes before linearized reading' (duration: 977.271333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:33.076415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:32.09894Z","time spent":"977.467088ms","remote":"127.0.0.1:53882","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-19T02:27:33.076703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.153974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:27:33.076729Z","caller":"traceutil/trace.go:171","msg":"trace[2042540109] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:544; }","duration":"299.217896ms","start":"2026-02-19T02:27:32.777504Z","end":"2026-02-19T02:27:33.076721Z","steps":["trace[2042540109] 'agreement among raft nodes before linearized reading' (duration: 299.176875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:33.076789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.459129ms","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-19T02:27:33.076886Z","caller":"traceutil/trace.go:171","msg":"trace[1357042790] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:544; }","duration":"975.574782ms","start":"2026-02-19T02:27:32.101301Z","end":"2026-02-19T02:27:33.076876Z","steps":["trace[1357042790] 'agreement among raft nodes before linearized reading' (duration: 975.379187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:33.076948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:32.101293Z","time spent":"975.644494ms","remote":"127.0.0.1:53882","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-19T02:27:33.077067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.785723ms","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-19T02:27:33.077085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.124061ms","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-19T02:27:33.077116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.694998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:27:33.077135Z","caller":"traceutil/trace.go:171","msg":"trace[838654255] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:544; }","duration":"802.74774ms","start":"2026-02-19T02:27:32.274383Z","end":"2026-02-19T02:27:33.077131Z","steps":["trace[838654255] 'agreement among raft nodes before linearized reading' (duration: 802.722639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:33.077151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:32.274365Z","time spent":"802.782761ms","remote":"127.0.0.1:53650","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-19T02:27:33.077144Z","caller":"traceutil/trace.go:171","msg":"trace[1226918221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:544; }","duration":"975.165152ms","start":"2026-02-19T02:27:32.101942Z","end":"2026-02-19T02:27:33.077107Z","steps":["trace[1226918221] 'agreement among raft nodes before linearized reading' (duration: 975.109331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:33.077257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:32.101936Z","time spent":"975.288645ms","remote":"127.0.0.1:53882","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-19T02:27:33.077093Z","caller":"traceutil/trace.go:171","msg":"trace[489521980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:544; }","duration":"974.824445ms","start":"2026-02-19T02:27:32.102263Z","end":"2026-02-19T02:27:33.077087Z","steps":["trace[489521980] 'agreement among raft nodes before linearized reading' (duration: 974.782384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:33.07735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:32.102257Z","time spent":"975.085671ms","remote":"127.0.0.1:53882","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-19T02:27:37.23875Z","caller":"traceutil/trace.go:171","msg":"trace[1890537803] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"124.720454ms","start":"2026-02-19T02:27:37.114012Z","end":"2026-02-19T02:27:37.238732Z","steps":["trace[1890537803] 'process raft request' (duration: 124.610252ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:37.294311Z","caller":"traceutil/trace.go:171","msg":"trace[1440404832] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"179.148334ms","start":"2026-02-19T02:27:37.115134Z","end":"2026-02-19T02:27:37.294283Z","steps":["trace[1440404832] 'process raft request' (duration: 168.229091ms)","trace[1440404832] 'compare' (duration: 10.765699ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:27:37.294466Z","caller":"traceutil/trace.go:171","msg":"trace[1626406428] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"179.280717ms","start":"2026-02-19T02:27:37.11517Z","end":"2026-02-19T02:27:37.294451Z","steps":["trace[1626406428] 'process raft request' (duration: 179.069682ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:37.294668Z","caller":"traceutil/trace.go:171","msg":"trace[937958680] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"179.472702ms","start":"2026-02-19T02:27:37.115169Z","end":"2026-02-19T02:27:37.294642Z","steps":["trace[937958680] 'process raft request' (duration: 179.202185ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:37.294671Z","caller":"traceutil/trace.go:171","msg":"trace[1102352407] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"179.41198ms","start":"2026-02-19T02:27:37.11523Z","end":"2026-02-19T02:27:37.294642Z","steps":["trace[1102352407] 'process raft request' (duration: 179.314778ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:40.76633Z","caller":"traceutil/trace.go:171","msg":"trace[1116790511] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"492.239404ms","start":"2026-02-19T02:27:40.274066Z","end":"2026-02-19T02:27:40.766305Z","steps":["trace[1116790511] 'read index received' (duration: 492.026779ms)","trace[1116790511] 'applied index is now lower than readState.Index' (duration: 211.885µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:27:40.766471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.390787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:27:40.766542Z","caller":"traceutil/trace.go:171","msg":"trace[1023891715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"492.576081ms","start":"2026-02-19T02:27:40.273956Z","end":"2026-02-19T02:27:40.766532Z","steps":["trace[1023891715] 'agreement among raft nodes before linearized reading' (duration: 492.459678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:40.766584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:40.273931Z","time spent":"492.645403ms","remote":"127.0.0.1:53650","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-19T02:27:40.766599Z","caller":"traceutil/trace.go:171","msg":"trace[1831496490] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"646.263523ms","start":"2026-02-19T02:27:40.120239Z","end":"2026-02-19T02:27:40.766503Z","steps":["trace[1831496490] 'process raft request' (duration: 645.914504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:40.766818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:40.120212Z","time spent":"646.517959ms","remote":"127.0.0.1:53814","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-19T02:27:41.807681Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890116500759505386,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T02:27:41.962175Z","caller":"traceutil/trace.go:171","msg":"trace[594556146] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"702.552018ms","start":"2026-02-19T02:27:41.259597Z","end":"2026-02-19T02:27:41.962149Z","steps":["trace[594556146] 'process raft request' (duration: 702.338863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:41.962411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:41.25957Z","time spent":"702.749973ms","remote":"127.0.0.1:53882","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-19T02:27:42.424659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.31488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:27:42.424784Z","caller":"traceutil/trace.go:171","msg":"trace[563428688] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:602; }","duration":"1.117978521s","start":"2026-02-19T02:27:41.306773Z","end":"2026-02-19T02:27:42.424751Z","steps":["trace[563428688] 'read index received' (duration: 655.246109ms)","trace[563428688] 'applied index is now lower than readState.Index' (duration: 462.731272ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:27:42.424824Z","caller":"traceutil/trace.go:171","msg":"trace[2015177370] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"746.518496ms","start":"2026-02-19T02:27:41.678271Z","end":"2026-02-19T02:27:42.424789Z","steps":["trace[2015177370] 'process raft request' (duration: 345.986601ms)","trace[2015177370] 'compare' (duration: 400.131086ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:27:42.424973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.656591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:27:42.424978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:41.678244Z","time spent":"746.65046ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:27:42.425012Z","caller":"traceutil/trace.go:171","msg":"trace[98548994] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"151.717903ms","start":"2026-02-19T02:27:42.273283Z","end":"2026-02-19T02:27:42.425001Z","steps":["trace[98548994] 'agreement among raft nodes before linearized reading' (duration: 151.630991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:42.425039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.118247788s","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-19T02:27:42.425126Z","caller":"traceutil/trace.go:171","msg":"trace[333939503] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"1.118379421s","start":"2026-02-19T02:27:41.306728Z","end":"2026-02-19T02:27:42.425108Z","steps":["trace[333939503] 'agreement among raft nodes before linearized reading' (duration: 1.118130185s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:42.425222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:41.306709Z","time spent":"1.118503024s","remote":"127.0.0.1:53882","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-19T02:27:42.425255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116955816s","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-19T02:27:42.425248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11584168s","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-19T02:27:42.425393Z","caller":"traceutil/trace.go:171","msg":"trace[533871299] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:585; }","duration":"1.116036314s","start":"2026-02-19T02:27:41.309332Z","end":"2026-02-19T02:27:42.425368Z","steps":["trace[533871299] 'agreement among raft nodes before linearized reading' (duration: 1.115648105s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:42.425454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:41.309277Z","time spent":"1.116165878s","remote":"127.0.0.1:53882","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-19T02:27:42.425273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.079994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.222\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-19T02:27:42.425512Z","caller":"traceutil/trace.go:171","msg":"trace[1225804609] range","detail":"{range_begin:/registry/masterleases/199.204.45.222; range_end:; response_count:1; response_revision:585; }","duration":"346.37382ms","start":"2026-02-19T02:27:42.079125Z","end":"2026-02-19T02:27:42.425499Z","steps":["trace[1225804609] 'agreement among raft nodes before linearized reading' (duration: 345.920419ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:42.42531Z","caller":"traceutil/trace.go:171","msg":"trace[1913444542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"1.117050038s","start":"2026-02-19T02:27:41.308246Z","end":"2026-02-19T02:27:42.425296Z","steps":["trace[1913444542] 'agreement among raft nodes before linearized reading' (duration: 1.116817023s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:42.425573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:42.079104Z","time spent":"346.457833ms","remote":"127.0.0.1:53656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.222\" "} {"level":"warn","ts":"2026-02-19T02:27:42.425284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117957551s","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-19T02:27:42.425633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:41.308229Z","time spent":"1.117376347s","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-19T02:27:42.425693Z","caller":"traceutil/trace.go:171","msg":"trace[1680381905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:585; }","duration":"1.118423162s","start":"2026-02-19T02:27:41.307257Z","end":"2026-02-19T02:27:42.425681Z","steps":["trace[1680381905] 'agreement among raft nodes before linearized reading' (duration: 1.117621883s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:42.42575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:41.307239Z","time spent":"1.118495364s","remote":"127.0.0.1:53882","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-19T02:27:43.975355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.481407ms","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-19T02:27:43.975444Z","caller":"traceutil/trace.go:171","msg":"trace[493435630] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:585; }","duration":"565.579339ms","start":"2026-02-19T02:27:43.409853Z","end":"2026-02-19T02:27:43.975432Z","steps":["trace[493435630] 'range keys from in-memory index tree' (duration: 565.470757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:43.975566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.321594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:27:43.97572Z","caller":"traceutil/trace.go:171","msg":"trace[1240178850] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.545781694s","start":"2026-02-19T02:27:42.429925Z","end":"2026-02-19T02:27:43.975707Z","steps":["trace[1240178850] 'process raft request' (duration: 583.250045ms)","trace[1240178850] 'compare' (duration: 961.923834ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:27:43.975764Z","caller":"traceutil/trace.go:171","msg":"trace[388636055] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.544881262s","start":"2026-02-19T02:27:42.43087Z","end":"2026-02-19T02:27:43.975751Z","steps":["trace[388636055] 'process raft request' (duration: 1.544832371s)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:43.97578Z","caller":"traceutil/trace.go:171","msg":"trace[1467044167] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"1.545141588s","start":"2026-02-19T02:27:42.430621Z","end":"2026-02-19T02:27:43.975763Z","steps":["trace[1467044167] 'process raft request' (duration: 1.545021035s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:43.975812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:42.429907Z","time spent":"1.545845565s","remote":"127.0.0.1:53882","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-19T02:27:43.975817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:42.430859Z","time spent":"1.544935554s","remote":"127.0.0.1:53882","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-19T02:27:43.975871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:42.430603Z","time spent":"1.54522135s","remote":"127.0.0.1:53882","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-19T02:27:43.976334Z","caller":"traceutil/trace.go:171","msg":"trace[1800167496] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.544958154s","start":"2026-02-19T02:27:42.431363Z","end":"2026-02-19T02:27:43.976321Z","steps":["trace[1800167496] 'process raft request' (duration: 1.54436127s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:43.976408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:42.431353Z","time spent":"1.545018995s","remote":"127.0.0.1:53882","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-19T02:27:43.976571Z","caller":"traceutil/trace.go:171","msg":"trace[993027527] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:605; }","duration":"702.592799ms","start":"2026-02-19T02:27:43.273968Z","end":"2026-02-19T02:27:43.976561Z","steps":["trace[993027527] 'read index received' (duration: 280.463404ms)","trace[993027527] 'applied index is now lower than readState.Index' (duration: 422.128205ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:27:43.976656Z","caller":"traceutil/trace.go:171","msg":"trace[291290888] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"1.364295893s","start":"2026-02-19T02:27:42.612351Z","end":"2026-02-19T02:27:43.976647Z","steps":["trace[291290888] 'process raft request' (duration: 1.363916434s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:43.976711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:42.612323Z","time spent":"1.364358225s","remote":"127.0.0.1:53656","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-19T02:27:43.976823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.845875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:27:43.976861Z","caller":"traceutil/trace.go:171","msg":"trace[723000411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"702.937437ms","start":"2026-02-19T02:27:43.273915Z","end":"2026-02-19T02:27:43.976852Z","steps":["trace[723000411] 'agreement among raft nodes before linearized reading' (duration: 702.881786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:43.976881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:43.273889Z","time spent":"702.987528ms","remote":"127.0.0.1:53650","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-19T02:27:47.513128Z","caller":"traceutil/trace.go:171","msg":"trace[948437671] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"240.143892ms","start":"2026-02-19T02:27:47.272889Z","end":"2026-02-19T02:27:47.513033Z","steps":["trace[948437671] 'read index received' (duration: 239.914867ms)","trace[948437671] 'applied index is now lower than readState.Index' (duration: 227.795µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:27:47.513186Z","caller":"traceutil/trace.go:171","msg":"trace[1223309676] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"362.384947ms","start":"2026-02-19T02:27:47.150774Z","end":"2026-02-19T02:27:47.513159Z","steps":["trace[1223309676] 'process raft request' (duration: 362.1159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:47.513269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.358558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:27:47.513315Z","caller":"traceutil/trace.go:171","msg":"trace[460429523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"240.45742ms","start":"2026-02-19T02:27:47.272843Z","end":"2026-02-19T02:27:47.513301Z","steps":["trace[460429523] 'agreement among raft nodes before linearized reading' (duration: 240.368898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:47.513319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:27:47.150734Z","time spent":"362.51119ms","remote":"127.0.0.1:53814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17655,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:27:54.266528Z","caller":"traceutil/trace.go:171","msg":"trace[2021921711] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"119.824856ms","start":"2026-02-19T02:27:54.146685Z","end":"2026-02-19T02:27:54.26651Z","steps":["trace[2021921711] 'read index received' (duration: 119.676483ms)","trace[2021921711] 'applied index is now lower than readState.Index' (duration: 147.793µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:27:54.266729Z","caller":"traceutil/trace.go:171","msg":"trace[1329012687] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"174.232975ms","start":"2026-02-19T02:27:54.092489Z","end":"2026-02-19T02:27:54.266722Z","steps":["trace[1329012687] 'process raft request' (duration: 173.918768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:54.267106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.40272ms","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-19T02:27:54.267193Z","caller":"traceutil/trace.go:171","msg":"trace[908002915] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"120.541263ms","start":"2026-02-19T02:27:54.146632Z","end":"2026-02-19T02:27:54.267174Z","steps":["trace[908002915] 'agreement among raft nodes before linearized reading' (duration: 120.271367ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:58.366078Z","caller":"traceutil/trace.go:171","msg":"trace[1423166716] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"145.447873ms","start":"2026-02-19T02:27:58.220603Z","end":"2026-02-19T02:27:58.366051Z","steps":["trace[1423166716] 'process raft request' (duration: 145.138546ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:58.630526Z","caller":"traceutil/trace.go:171","msg":"trace[591579183] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"259.978451ms","start":"2026-02-19T02:27:58.370514Z","end":"2026-02-19T02:27:58.630492Z","steps":["trace[591579183] 'process raft request' (duration: 259.323215ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:58.630792Z","caller":"traceutil/trace.go:171","msg":"trace[1914707660] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"260.184686ms","start":"2026-02-19T02:27:58.370575Z","end":"2026-02-19T02:27:58.63076Z","steps":["trace[1914707660] 'process raft request' (duration: 259.856158ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:58.630946Z","caller":"traceutil/trace.go:171","msg":"trace[1338624904] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:712; }","duration":"257.609063ms","start":"2026-02-19T02:27:58.373321Z","end":"2026-02-19T02:27:58.63093Z","steps":["trace[1338624904] 'read index received' (duration: 40.048405ms)","trace[1338624904] 'applied index is now lower than readState.Index' (duration: 217.559728ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:27:58.630927Z","caller":"traceutil/trace.go:171","msg":"trace[1835794748] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"260.252548ms","start":"2026-02-19T02:27:58.370619Z","end":"2026-02-19T02:27:58.630872Z","steps":["trace[1835794748] 'process raft request' (duration: 260.069623ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:58.631034Z","caller":"traceutil/trace.go:171","msg":"trace[982606525] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"256.016345ms","start":"2026-02-19T02:27:58.375003Z","end":"2026-02-19T02:27:58.631019Z","steps":["trace[982606525] 'process raft request' (duration: 255.82064ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:58.631174Z","caller":"traceutil/trace.go:171","msg":"trace[445858942] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"255.80434ms","start":"2026-02-19T02:27:58.375358Z","end":"2026-02-19T02:27:58.631163Z","steps":["trace[445858942] 'process raft request' (duration: 255.522253ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:58.631232Z","caller":"traceutil/trace.go:171","msg":"trace[838392137] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"259.621871ms","start":"2026-02-19T02:27:58.371595Z","end":"2026-02-19T02:27:58.631217Z","steps":["trace[838392137] 'process raft request' (duration: 259.191231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:58.631439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.096835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-brnhd\" ","response":"range_response_count:1 size:4990"} {"level":"info","ts":"2026-02-19T02:27:58.631476Z","caller":"traceutil/trace.go:171","msg":"trace[1105815313] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-brnhd; range_end:; response_count:1; response_revision:694; }","duration":"258.167457ms","start":"2026-02-19T02:27:58.373296Z","end":"2026-02-19T02:27:58.631464Z","steps":["trace[1105815313] 'agreement among raft nodes before linearized reading' (duration: 258.019383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:27:58.631493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.195598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:27:58.631542Z","caller":"traceutil/trace.go:171","msg":"trace[1951739549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"143.298811ms","start":"2026-02-19T02:27:58.488225Z","end":"2026-02-19T02:27:58.631523Z","steps":["trace[1951739549] 'agreement among raft nodes before linearized reading' (duration: 143.199099ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:58.927263Z","caller":"traceutil/trace.go:171","msg":"trace[1041272641] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"126.726882ms","start":"2026-02-19T02:27:58.800511Z","end":"2026-02-19T02:27:58.927238Z","steps":["trace[1041272641] 'process raft request' (duration: 126.67793ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:58.927285Z","caller":"traceutil/trace.go:171","msg":"trace[1842791281] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"131.042266ms","start":"2026-02-19T02:27:58.796203Z","end":"2026-02-19T02:27:58.927246Z","steps":["trace[1842791281] 'process raft request' (duration: 130.80702ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:58.927333Z","caller":"traceutil/trace.go:171","msg":"trace[374212535] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"127.372147ms","start":"2026-02-19T02:27:58.799909Z","end":"2026-02-19T02:27:58.927281Z","steps":["trace[374212535] 'process raft request' (duration: 127.229723ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:27:58.955664Z","caller":"traceutil/trace.go:171","msg":"trace[578539962] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"101.503345ms","start":"2026-02-19T02:27:58.853894Z","end":"2026-02-19T02:27:58.955397Z","steps":["trace[578539962] 'process raft request' (duration: 101.362471ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:00.432821Z","caller":"traceutil/trace.go:171","msg":"trace[90739326] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:742; }","duration":"160.615017ms","start":"2026-02-19T02:28:00.272182Z","end":"2026-02-19T02:28:00.432797Z","steps":["trace[90739326] 'read index received' (duration: 160.454654ms)","trace[90739326] 'applied index is now lower than readState.Index' (duration: 158.963µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:28:00.432916Z","caller":"traceutil/trace.go:171","msg":"trace[1370666639] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"205.58396ms","start":"2026-02-19T02:28:00.227278Z","end":"2026-02-19T02:28:00.432862Z","steps":["trace[1370666639] 'process raft request' (duration: 205.373025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:00.433118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.912334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:28:00.433201Z","caller":"traceutil/trace.go:171","msg":"trace[553457266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:719; }","duration":"161.037228ms","start":"2026-02-19T02:28:00.272142Z","end":"2026-02-19T02:28:00.433179Z","steps":["trace[553457266] 'agreement among raft nodes before linearized reading' (duration: 160.818142ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:05.433423Z","caller":"traceutil/trace.go:171","msg":"trace[2046138323] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"167.149044ms","start":"2026-02-19T02:28:05.266253Z","end":"2026-02-19T02:28:05.433402Z","steps":["trace[2046138323] 'process raft request' (duration: 167.026501ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:06.579232Z","caller":"traceutil/trace.go:171","msg":"trace[1530160303] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"110.097011ms","start":"2026-02-19T02:28:06.469083Z","end":"2026-02-19T02:28:06.57918Z","steps":["trace[1530160303] 'process raft request' (duration: 31.54353ms)","trace[1530160303] 'compare' (duration: 78.358926ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:28:07.237315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.868086ms","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-19T02:28:07.23737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.579972ms","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-19T02:28:07.237409Z","caller":"traceutil/trace.go:171","msg":"trace[1985524139] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:740; }","duration":"532.00215ms","start":"2026-02-19T02:28:06.705387Z","end":"2026-02-19T02:28:07.237389Z","steps":["trace[1985524139] 'range keys from in-memory index tree' (duration: 531.722853ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:07.237452Z","caller":"traceutil/trace.go:171","msg":"trace[1582852789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:740; }","duration":"534.735855ms","start":"2026-02-19T02:28:06.702698Z","end":"2026-02-19T02:28:07.237433Z","steps":["trace[1582852789] 'range keys from in-memory index tree' (duration: 534.368557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:07.237462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:06.70538Z","time spent":"532.072283ms","remote":"127.0.0.1:53882","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-19T02:28:07.237493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:06.70267Z","time spent":"534.814047ms","remote":"127.0.0.1:53882","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-19T02:28:07.237511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.316308ms","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-19T02:28:07.237601Z","caller":"traceutil/trace.go:171","msg":"trace[135104177] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:740; }","duration":"532.470672ms","start":"2026-02-19T02:28:06.705111Z","end":"2026-02-19T02:28:07.237582Z","steps":["trace[135104177] 'range keys from in-memory index tree' (duration: 531.909609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:07.237642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:06.705078Z","time spent":"532.554314ms","remote":"127.0.0.1:53882","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-19T02:28:07.237748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.062528ms","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-19T02:28:07.237807Z","caller":"traceutil/trace.go:171","msg":"trace[517927994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:740; }","duration":"531.182642ms","start":"2026-02-19T02:28:06.706608Z","end":"2026-02-19T02:28:07.237791Z","steps":["trace[517927994] 'range keys from in-memory index tree' (duration: 530.381171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:07.237852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:06.706594Z","time spent":"531.248653ms","remote":"127.0.0.1:53882","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-19T02:28:08.328268Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890116500759505686,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:28:08.829002Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890116500759505686,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:28:09.329597Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890116500759505686,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:28:09.830277Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890116500759505686,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:28:10.272769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000409561s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-19T02:28:10.272832Z","caller":"traceutil/trace.go:171","msg":"trace[1227589951] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000511053s","start":"2026-02-19T02:28:08.272308Z","end":"2026-02-19T02:28:10.272819Z","steps":["trace[1227589951] 'agreement among raft nodes before linearized reading' (duration: 2.000434572s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:10.272896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:08.272291Z","time spent":"2.000597265s","remote":"127.0.0.1:53650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T02:28:10.330665Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890116500759505686,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:28:10.649549Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.407206648s","expected-duration":"1s"} {"level":"info","ts":"2026-02-19T02:28:10.650254Z","caller":"traceutil/trace.go:171","msg":"trace[2089023142] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"3.407997606s","start":"2026-02-19T02:28:07.242214Z","end":"2026-02-19T02:28:10.650211Z","steps":["trace[2089023142] 'process raft request' (duration: 3.407670849s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:10.650533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:07.242195Z","time spent":"3.408190362s","remote":"127.0.0.1:53882","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-19T02:28:10.793279Z","caller":"traceutil/trace.go:171","msg":"trace[1123972777] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"3.550257103s","start":"2026-02-19T02:28:07.243001Z","end":"2026-02-19T02:28:10.793258Z","steps":["trace[1123972777] 'process raft request' (duration: 3.463198727s)","trace[1123972777] 'compare' (duration: 86.744969ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:28:10.793587Z","caller":"traceutil/trace.go:171","msg":"trace[1191682515] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"3.549419702s","start":"2026-02-19T02:28:07.244155Z","end":"2026-02-19T02:28:10.793574Z","steps":["trace[1191682515] 'process raft request' (duration: 3.549044233s)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:10.793599Z","caller":"traceutil/trace.go:171","msg":"trace[1450611711] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"3.549399712s","start":"2026-02-19T02:28:07.244187Z","end":"2026-02-19T02:28:10.793587Z","steps":["trace[1450611711] 'process raft request' (duration: 3.549199837s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:10.793667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:07.244147Z","time spent":"3.549475994s","remote":"127.0.0.1:53882","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-19T02:28:10.793668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:07.244159Z","time spent":"3.549467223s","remote":"127.0.0.1:53882","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-19T02:28:10.793886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:07.242982Z","time spent":"3.550381436s","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:28:10.842853Z","caller":"traceutil/trace.go:171","msg":"trace[570704024] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:766; }","duration":"3.014910751s","start":"2026-02-19T02:28:07.827911Z","end":"2026-02-19T02:28:10.842822Z","steps":["trace[570704024] 'read index received' (duration: 2.821851483s)","trace[570704024] 'applied index is now lower than readState.Index' (duration: 193.055258ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:28:10.843079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.259363157s","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-19T02:28:10.843107Z","caller":"traceutil/trace.go:171","msg":"trace[857280762] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:744; }","duration":"2.259448279s","start":"2026-02-19T02:28:08.583651Z","end":"2026-02-19T02:28:10.843099Z","steps":["trace[857280762] 'agreement among raft nodes before linearized reading' (duration: 2.259308274s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:10.843137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:08.583632Z","time spent":"2.25949806s","remote":"127.0.0.1:53882","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-19T02:28:10.843169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.66467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:28:10.843134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.015175198s","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-19T02:28:10.843244Z","caller":"traceutil/trace.go:171","msg":"trace[1430599046] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:744; }","duration":"530.781902ms","start":"2026-02-19T02:28:10.312447Z","end":"2026-02-19T02:28:10.843229Z","steps":["trace[1430599046] 'agreement among raft nodes before linearized reading' (duration: 530.654889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:10.843284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:10.312433Z","time spent":"530.843954ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-02-19T02:28:10.843295Z","caller":"traceutil/trace.go:171","msg":"trace[1598353032] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:744; }","duration":"3.015411864s","start":"2026-02-19T02:28:07.827855Z","end":"2026-02-19T02:28:10.843266Z","steps":["trace[1598353032] 'agreement among raft nodes before linearized reading' (duration: 3.015117886s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:10.8433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.049888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:28:10.843366Z","caller":"traceutil/trace.go:171","msg":"trace[1588046204] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:744; }","duration":"570.155811ms","start":"2026-02-19T02:28:10.273192Z","end":"2026-02-19T02:28:10.843348Z","steps":["trace[1588046204] 'agreement among raft nodes before linearized reading' (duration: 570.042708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:10.84338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:07.827833Z","time spent":"3.015525076s","remote":"127.0.0.1:54066","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-19T02:28:10.843414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:10.273171Z","time spent":"570.233202ms","remote":"127.0.0.1:53652","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-19T02:28:10.967712Z","caller":"traceutil/trace.go:171","msg":"trace[1136179079] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"120.303877ms","start":"2026-02-19T02:28:10.847387Z","end":"2026-02-19T02:28:10.96769Z","steps":["trace[1136179079] 'process raft request' (duration: 120.163854ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:10.989399Z","caller":"traceutil/trace.go:171","msg":"trace[435539342] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:771; }","duration":"136.782734ms","start":"2026-02-19T02:28:10.852592Z","end":"2026-02-19T02:28:10.989374Z","steps":["trace[435539342] 'read index received' (duration: 114.890436ms)","trace[435539342] 'applied index is now lower than readState.Index' (duration: 21.891288ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:28:10.989479Z","caller":"traceutil/trace.go:171","msg":"trace[1759191431] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"141.377905ms","start":"2026-02-19T02:28:10.84808Z","end":"2026-02-19T02:28:10.989458Z","steps":["trace[1759191431] 'process raft request' (duration: 141.119998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:10.989555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.944237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:28:10.98961Z","caller":"traceutil/trace.go:171","msg":"trace[718530531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"137.03038ms","start":"2026-02-19T02:28:10.852566Z","end":"2026-02-19T02:28:10.989596Z","steps":["trace[718530531] 'agreement among raft nodes before linearized reading' (duration: 136.892617ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:12.563854Z","caller":"traceutil/trace.go:171","msg":"trace[1498423835] linearizableReadLoop","detail":"{readStateIndex:775; appliedIndex:774; }","duration":"386.372864ms","start":"2026-02-19T02:28:12.177446Z","end":"2026-02-19T02:28:12.563819Z","steps":["trace[1498423835] 'read index received' (duration: 386.112368ms)","trace[1498423835] 'applied index is now lower than readState.Index' (duration: 259.356µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:28:12.563891Z","caller":"traceutil/trace.go:171","msg":"trace[22318589] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"405.660849ms","start":"2026-02-19T02:28:12.158197Z","end":"2026-02-19T02:28:12.563858Z","steps":["trace[22318589] 'process raft request' (duration: 405.352192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:12.564072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:12.158174Z","time spent":"405.811052ms","remote":"127.0.0.1:53656","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-19T02:28:12.564079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.62584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:28:12.564123Z","caller":"traceutil/trace.go:171","msg":"trace[1735683977] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:747; }","duration":"386.748293ms","start":"2026-02-19T02:28:12.177363Z","end":"2026-02-19T02:28:12.564111Z","steps":["trace[1735683977] 'agreement among raft nodes before linearized reading' (duration: 386.60873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:12.564181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:12.17734Z","time spent":"386.830034ms","remote":"127.0.0.1:53928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-19T02:28:12.564475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.071125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:28:12.564521Z","caller":"traceutil/trace.go:171","msg":"trace[898235274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:747; }","duration":"290.116316ms","start":"2026-02-19T02:28:12.274394Z","end":"2026-02-19T02:28:12.56451Z","steps":["trace[898235274] 'agreement among raft nodes before linearized reading' (duration: 290.040125ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:12.889909Z","caller":"traceutil/trace.go:171","msg":"trace[967200164] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"229.928096ms","start":"2026-02-19T02:28:12.659954Z","end":"2026-02-19T02:28:12.889882Z","steps":["trace[967200164] 'process raft request' (duration: 229.749922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:14.339132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.724004ms","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-19T02:28:14.339348Z","caller":"traceutil/trace.go:171","msg":"trace[1694940343] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:752; }","duration":"928.95034ms","start":"2026-02-19T02:28:13.410387Z","end":"2026-02-19T02:28:14.339337Z","steps":["trace[1694940343] 'range keys from in-memory index tree' (duration: 928.711683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:14.339547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.625448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:28:14.339993Z","caller":"traceutil/trace.go:171","msg":"trace[787711805] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"1.343977904s","start":"2026-02-19T02:28:12.995954Z","end":"2026-02-19T02:28:14.339931Z","steps":["trace[787711805] 'process raft request' (duration: 937.857695ms)","trace[787711805] 'compare' (duration: 405.403232ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:28:14.340155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:12.995938Z","time spent":"1.344123757s","remote":"127.0.0.1:53882","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-19T02:28:15.306069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.203596ms","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-19T02:28:15.30613Z","caller":"traceutil/trace.go:171","msg":"trace[1233510064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:753; }","duration":"412.314669ms","start":"2026-02-19T02:28:14.893803Z","end":"2026-02-19T02:28:15.306118Z","steps":["trace[1233510064] 'range keys from in-memory index tree' (duration: 411.98375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:15.30616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:14.893782Z","time spent":"412.371101ms","remote":"127.0.0.1:53882","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-19T02:28:15.30617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.641905ms","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-19T02:28:15.306199Z","caller":"traceutil/trace.go:171","msg":"trace[782820095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:753; }","duration":"321.704627ms","start":"2026-02-19T02:28:14.984486Z","end":"2026-02-19T02:28:15.306191Z","steps":["trace[782820095] 'range keys from in-memory index tree' (duration: 321.527123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:15.306218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:14.984466Z","time spent":"321.748088ms","remote":"127.0.0.1:53882","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-19T02:28:15.306296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.584455ms","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-19T02:28:15.306331Z","caller":"traceutil/trace.go:171","msg":"trace[1276972929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:753; }","duration":"321.629685ms","start":"2026-02-19T02:28:14.98469Z","end":"2026-02-19T02:28:15.30632Z","steps":["trace[1276972929] 'range keys from in-memory index tree' (duration: 321.485982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:15.306347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:14.984684Z","time spent":"321.659496ms","remote":"127.0.0.1:53882","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-19T02:28:15.306447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.251916ms","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-19T02:28:15.306469Z","caller":"traceutil/trace.go:171","msg":"trace[242392548] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:753; }","duration":"321.301458ms","start":"2026-02-19T02:28:14.985159Z","end":"2026-02-19T02:28:15.306461Z","steps":["trace[242392548] 'range keys from in-memory index tree' (duration: 321.163834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:15.306489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:14.985143Z","time spent":"321.341218ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-19T02:28:15.548208Z","caller":"traceutil/trace.go:171","msg":"trace[1207363558] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"238.435001ms","start":"2026-02-19T02:28:15.309753Z","end":"2026-02-19T02:28:15.548188Z","steps":["trace[1207363558] 'process raft request' (duration: 238.300438ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:15.746962Z","caller":"traceutil/trace.go:171","msg":"trace[283755270] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:782; }","duration":"173.923789ms","start":"2026-02-19T02:28:15.57302Z","end":"2026-02-19T02:28:15.746944Z","steps":["trace[283755270] 'read index received' (duration: 170.458465ms)","trace[283755270] 'applied index is now lower than readState.Index' (duration: 3.464564ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:28:15.747128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.097622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/coredns-5dd5756b68-brnhd.189584d693543baf\" ","response":"range_response_count:1 size:832"} {"level":"info","ts":"2026-02-19T02:28:15.74716Z","caller":"traceutil/trace.go:171","msg":"trace[867129606] range","detail":"{range_begin:/registry/events/kube-system/coredns-5dd5756b68-brnhd.189584d693543baf; range_end:; response_count:1; response_revision:757; }","duration":"174.178585ms","start":"2026-02-19T02:28:15.572971Z","end":"2026-02-19T02:28:15.74715Z","steps":["trace[867129606] 'agreement among raft nodes before linearized reading' (duration: 174.046591ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:15.747186Z","caller":"traceutil/trace.go:171","msg":"trace[396131168] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"436.84319ms","start":"2026-02-19T02:28:15.310304Z","end":"2026-02-19T02:28:15.747147Z","steps":["trace[396131168] 'process raft request' (duration: 436.584473ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:15.747118Z","caller":"traceutil/trace.go:171","msg":"trace[892466460] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"436.781398ms","start":"2026-02-19T02:28:15.31031Z","end":"2026-02-19T02:28:15.747091Z","steps":["trace[892466460] 'process raft request' (duration: 433.264624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:15.747513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:15.310278Z","time spent":"437.093116ms","remote":"127.0.0.1:53882","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-19T02:28:15.747515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:15.310294Z","time spent":"437.129947ms","remote":"127.0.0.1:53882","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-19T02:28:15.747383Z","caller":"traceutil/trace.go:171","msg":"trace[1346107592] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"436.983423ms","start":"2026-02-19T02:28:15.310392Z","end":"2026-02-19T02:28:15.747376Z","steps":["trace[1346107592] 'process raft request' (duration: 436.523502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:15.74771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:15.310368Z","time spent":"437.27845ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:28:16.417405Z","caller":"traceutil/trace.go:171","msg":"trace[1156349649] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:787; }","duration":"145.465673ms","start":"2026-02-19T02:28:16.271917Z","end":"2026-02-19T02:28:16.417383Z","steps":["trace[1156349649] 'read index received' (duration: 145.293869ms)","trace[1156349649] 'applied index is now lower than readState.Index' (duration: 170.774µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:28:16.417453Z","caller":"traceutil/trace.go:171","msg":"trace[339686308] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"182.28039ms","start":"2026-02-19T02:28:16.235141Z","end":"2026-02-19T02:28:16.417421Z","steps":["trace[339686308] 'process raft request' (duration: 182.109696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:16.417572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.634447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:28:16.417624Z","caller":"traceutil/trace.go:171","msg":"trace[883623281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"145.727959ms","start":"2026-02-19T02:28:16.271883Z","end":"2026-02-19T02:28:16.417611Z","steps":["trace[883623281] 'agreement among raft nodes before linearized reading' (duration: 145.623117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:16.739139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.798411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:28:16.739299Z","caller":"traceutil/trace.go:171","msg":"trace[1805957976] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"314.862112ms","start":"2026-02-19T02:28:16.424423Z","end":"2026-02-19T02:28:16.739286Z","steps":["trace[1805957976] 'process raft request' (duration: 314.7982ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:16.739316Z","caller":"traceutil/trace.go:171","msg":"trace[928160460] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"317.556877ms","start":"2026-02-19T02:28:16.42173Z","end":"2026-02-19T02:28:16.739287Z","steps":["trace[928160460] 'process raft request' (duration: 193.54224ms)","trace[928160460] 'compare' (duration: 123.626867ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:28:16.739376Z","caller":"traceutil/trace.go:171","msg":"trace[1524284359] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"317.165058ms","start":"2026-02-19T02:28:16.422203Z","end":"2026-02-19T02:28:16.739368Z","steps":["trace[1524284359] 'read index received' (duration: 193.241043ms)","trace[1524284359] 'applied index is now lower than readState.Index' (duration: 123.923254ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:28:16.739462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:16.421708Z","time spent":"317.67661ms","remote":"127.0.0.1:53882","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-19T02:28:16.739492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.29636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-brnhd\" ","response":"range_response_count:1 size:5012"} {"level":"warn","ts":"2026-02-19T02:28:16.739347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:16.4244Z","time spent":"314.926454ms","remote":"127.0.0.1:53896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1451,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:28:16.739514Z","caller":"traceutil/trace.go:171","msg":"trace[2063589796] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-brnhd; range_end:; response_count:1; response_revision:762; }","duration":"317.343592ms","start":"2026-02-19T02:28:16.422162Z","end":"2026-02-19T02:28:16.739506Z","steps":["trace[2063589796] 'agreement among raft nodes before linearized reading' (duration: 317.235229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:16.739533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:16.422151Z","time spent":"317.377762ms","remote":"127.0.0.1:53814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5035,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-brnhd\" "} {"level":"info","ts":"2026-02-19T02:28:16.974776Z","caller":"traceutil/trace.go:171","msg":"trace[1918212056] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"228.065712ms","start":"2026-02-19T02:28:16.746692Z","end":"2026-02-19T02:28:16.974758Z","steps":["trace[1918212056] 'process raft request' (duration: 227.960669ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:17.058055Z","caller":"traceutil/trace.go:171","msg":"trace[531852961] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"254.009497ms","start":"2026-02-19T02:28:16.804024Z","end":"2026-02-19T02:28:17.058034Z","steps":["trace[531852961] 'process raft request' (duration: 253.868284ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:17.190248Z","caller":"traceutil/trace.go:171","msg":"trace[1944881762] transaction","detail":"{read_only:false; number_of_response:1; response_revision:764; }","duration":"213.47646ms","start":"2026-02-19T02:28:16.976743Z","end":"2026-02-19T02:28:17.190219Z","steps":["trace[1944881762] 'process raft request' (duration: 157.898142ms)","trace[1944881762] 'compare' (duration: 55.491896ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:28:17.509032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.333542ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-19T02:28:17.509143Z","caller":"traceutil/trace.go:171","msg":"trace[465841740] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:794; }","duration":"236.900054ms","start":"2026-02-19T02:28:17.272222Z","end":"2026-02-19T02:28:17.509122Z","steps":["trace[465841740] 'read index received' (duration: 88.396238ms)","trace[465841740] 'applied index is now lower than readState.Index' (duration: 148.502176ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:28:17.509225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.991246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:28:17.509274Z","caller":"traceutil/trace.go:171","msg":"trace[406955219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"237.045907ms","start":"2026-02-19T02:28:17.272216Z","end":"2026-02-19T02:28:17.509262Z","steps":["trace[406955219] 'agreement among raft nodes before linearized reading' (duration: 236.964895ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:17.716388Z","caller":"traceutil/trace.go:171","msg":"trace[617404608] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"160.248178ms","start":"2026-02-19T02:28:17.556123Z","end":"2026-02-19T02:28:17.716371Z","steps":["trace[617404608] 'process raft request' (duration: 160.120405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:18.053959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.804665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:28:18.05433Z","caller":"traceutil/trace.go:171","msg":"trace[1134982177] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"300.057765ms","start":"2026-02-19T02:28:17.754234Z","end":"2026-02-19T02:28:18.054292Z","steps":["trace[1134982177] 'process raft request' (duration: 157.835ms)","trace[1134982177] 'compare' (duration: 141.687171ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:28:18.054507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:17.754222Z","time spent":"300.203239ms","remote":"127.0.0.1:53882","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-19T02:28:18.054349Z","caller":"traceutil/trace.go:171","msg":"trace[1378887358] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"299.191815ms","start":"2026-02-19T02:28:17.755141Z","end":"2026-02-19T02:28:18.054333Z","steps":["trace[1378887358] 'process raft request' (duration: 299.132144ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:18.054741Z","caller":"traceutil/trace.go:171","msg":"trace[786260408] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"299.732757ms","start":"2026-02-19T02:28:17.754611Z","end":"2026-02-19T02:28:18.054344Z","steps":["trace[786260408] 'read index received' (duration: 157.470072ms)","trace[786260408] 'applied index is now lower than readState.Index' (duration: 142.260055ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:28:18.054908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.27783ms","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-19T02:28:18.054942Z","caller":"traceutil/trace.go:171","msg":"trace[1698151808] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:768; }","duration":"300.339812ms","start":"2026-02-19T02:28:17.754593Z","end":"2026-02-19T02:28:18.054933Z","steps":["trace[1698151808] 'agreement among raft nodes before linearized reading' (duration: 300.202269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:18.054969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:17.754585Z","time spent":"300.377113ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-19T02:28:27.1954Z","caller":"traceutil/trace.go:171","msg":"trace[237952462] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"399.674945ms","start":"2026-02-19T02:28:26.795699Z","end":"2026-02-19T02:28:27.195374Z","steps":["trace[237952462] 'process raft request' (duration: 399.47337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:27.195606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:26.795671Z","time spent":"399.814898ms","remote":"127.0.0.1:53882","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-19T02:28:27.423417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.930013ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-19T02:28:28.905421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.142964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:28:28.905543Z","caller":"traceutil/trace.go:171","msg":"trace[257007527] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"713.245825ms","start":"2026-02-19T02:28:28.192276Z","end":"2026-02-19T02:28:28.905522Z","steps":["trace[257007527] 'read index received' (duration: 261.826305ms)","trace[257007527] 'applied index is now lower than readState.Index' (duration: 451.41789ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:28:28.905821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.530171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-19T02:28:28.905868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.061358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:28:28.905891Z","caller":"traceutil/trace.go:171","msg":"trace[131300694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:796; }","duration":"713.644894ms","start":"2026-02-19T02:28:28.19223Z","end":"2026-02-19T02:28:28.905875Z","steps":["trace[131300694] 'agreement among raft nodes before linearized reading' (duration: 713.412989ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:28.905878Z","caller":"traceutil/trace.go:171","msg":"trace[473462142] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"762.718836ms","start":"2026-02-19T02:28:28.143126Z","end":"2026-02-19T02:28:28.905845Z","steps":["trace[473462142] 'process raft request' (duration: 311.05355ms)","trace[473462142] 'compare' (duration: 451.011371ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:28:28.905927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:28.192212Z","time spent":"713.708155ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-19T02:28:28.905942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.976623ms","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-19T02:28:28.906005Z","caller":"traceutil/trace.go:171","msg":"trace[1894112640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:796; }","duration":"416.197532ms","start":"2026-02-19T02:28:28.489752Z","end":"2026-02-19T02:28:28.905949Z","steps":["trace[1894112640] 'agreement among raft nodes before linearized reading' (duration: 416.063479ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:28.906041Z","caller":"traceutil/trace.go:171","msg":"trace[986214905] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:796; }","duration":"246.130157ms","start":"2026-02-19T02:28:28.659894Z","end":"2026-02-19T02:28:28.906024Z","steps":["trace[986214905] 'agreement among raft nodes before linearized reading' (duration: 245.938702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:28.906064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:28.489731Z","time spent":"416.318204ms","remote":"127.0.0.1:53650","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-19T02:28:28.906069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:28.143103Z","time spent":"762.841299ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:28:29.353799Z","caller":"traceutil/trace.go:171","msg":"trace[1517880219] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"149.757426ms","start":"2026-02-19T02:28:29.204015Z","end":"2026-02-19T02:28:29.353773Z","steps":["trace[1517880219] 'process raft request' (duration: 149.557291ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:29.917302Z","caller":"traceutil/trace.go:171","msg":"trace[1370869088] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"114.349933ms","start":"2026-02-19T02:28:29.802922Z","end":"2026-02-19T02:28:29.917272Z","steps":["trace[1370869088] 'process raft request' (duration: 114.185179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:31.160249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.552744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-19T02:28:31.160374Z","caller":"traceutil/trace.go:171","msg":"trace[826407769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:799; }","duration":"208.757448ms","start":"2026-02-19T02:28:30.951591Z","end":"2026-02-19T02:28:31.160348Z","steps":["trace[826407769] 'range keys from in-memory index tree' (duration: 208.306417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:31.160439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017488108s","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-19T02:28:31.160525Z","caller":"traceutil/trace.go:171","msg":"trace[1472310151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:799; }","duration":"1.017626091s","start":"2026-02-19T02:28:30.142879Z","end":"2026-02-19T02:28:31.160505Z","steps":["trace[1472310151] 'range keys from in-memory index tree' (duration: 1.017288473s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:31.160568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:30.142857Z","time spent":"1.017702892s","remote":"127.0.0.1:53882","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-19T02:28:31.160766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.776389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:28:31.160867Z","caller":"traceutil/trace.go:171","msg":"trace[647659963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"886.935163ms","start":"2026-02-19T02:28:30.27391Z","end":"2026-02-19T02:28:31.160845Z","steps":["trace[647659963] 'range keys from in-memory index tree' (duration: 886.655987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:31.160914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:30.273886Z","time spent":"887.017595ms","remote":"127.0.0.1:53650","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-19T02:28:31.161181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.979837ms","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-19T02:28:31.161236Z","caller":"traceutil/trace.go:171","msg":"trace[1929530346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"250.076139ms","start":"2026-02-19T02:28:30.911146Z","end":"2026-02-19T02:28:31.161222Z","steps":["trace[1929530346] 'range keys from in-memory index tree' (duration: 248.615036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:31.857956Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890116500759505849,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T02:28:32.109513Z","caller":"traceutil/trace.go:171","msg":"trace[788404169] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"944.315867ms","start":"2026-02-19T02:28:31.165142Z","end":"2026-02-19T02:28:32.109458Z","steps":["trace[788404169] 'process raft request' (duration: 944.187864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:32.10971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:31.165112Z","time spent":"944.47194ms","remote":"127.0.0.1:53882","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-19T02:28:32.670701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.216529ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:28:32.670891Z","caller":"traceutil/trace.go:171","msg":"trace[635665454] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:831; }","duration":"1.313692773s","start":"2026-02-19T02:28:31.357183Z","end":"2026-02-19T02:28:32.670876Z","steps":["trace[635665454] 'read index received' (duration: 752.206056ms)","trace[635665454] 'applied index is now lower than readState.Index' (duration: 561.485887ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:28:32.671044Z","caller":"traceutil/trace.go:171","msg":"trace[1294202365] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"1.504861042s","start":"2026-02-19T02:28:31.166152Z","end":"2026-02-19T02:28:32.671013Z","steps":["trace[1294202365] 'process raft request' (duration: 1.504661728s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:32.671081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.313899389s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T02:28:32.671093Z","caller":"traceutil/trace.go:171","msg":"trace[1682451187] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"1.505331122s","start":"2026-02-19T02:28:31.165733Z","end":"2026-02-19T02:28:32.671064Z","steps":["trace[1682451187] 'process raft request' (duration: 1.075671253s)","trace[1682451187] 'compare' (duration: 429.125897ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:28:32.671145Z","caller":"traceutil/trace.go:171","msg":"trace[1655027181] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:802; }","duration":"1.314004561s","start":"2026-02-19T02:28:31.357128Z","end":"2026-02-19T02:28:32.671133Z","steps":["trace[1655027181] 'agreement among raft nodes before linearized reading' (duration: 1.313914709s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:32.671211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:31.357106Z","time spent":"1.314068992s","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-02-19T02:28:32.671236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:31.16571Z","time spent":"1.505447166s","remote":"127.0.0.1:53882","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-19T02:28:32.671533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.245612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:28:32.671569Z","caller":"traceutil/trace.go:171","msg":"trace[1602622581] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"399.315684ms","start":"2026-02-19T02:28:32.272242Z","end":"2026-02-19T02:28:32.671558Z","steps":["trace[1602622581] 'agreement among raft nodes before linearized reading' (duration: 399.258922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:32.671599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:32.272221Z","time spent":"399.372576ms","remote":"127.0.0.1:53650","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-19T02:28:32.671604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.313828587s","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-19T02:28:32.671667Z","caller":"traceutil/trace.go:171","msg":"trace[51618355] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:802; }","duration":"1.313926769s","start":"2026-02-19T02:28:31.357727Z","end":"2026-02-19T02:28:32.671654Z","steps":["trace[51618355] 'agreement among raft nodes before linearized reading' (duration: 1.313760175s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:32.671722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:31.357707Z","time spent":"1.31398198s","remote":"127.0.0.1:53882","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-19T02:28:32.671982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077962037s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:28:32.671995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.91277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.222\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-19T02:28:32.672016Z","caller":"traceutil/trace.go:171","msg":"trace[1543221323] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:802; }","duration":"1.07805742s","start":"2026-02-19T02:28:31.593949Z","end":"2026-02-19T02:28:32.672006Z","steps":["trace[1543221323] 'agreement among raft nodes before linearized reading' (duration: 1.078002199s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:32.672048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:31.593925Z","time spent":"1.078115131s","remote":"127.0.0.1:53906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-19T02:28:32.672026Z","caller":"traceutil/trace.go:171","msg":"trace[290004013] range","detail":"{range_begin:/registry/masterleases/199.204.45.222; range_end:; response_count:1; response_revision:802; }","duration":"589.980801ms","start":"2026-02-19T02:28:32.082036Z","end":"2026-02-19T02:28:32.672017Z","steps":["trace[290004013] 'agreement among raft nodes before linearized reading' (duration: 589.866239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:32.672083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:32.082013Z","time spent":"590.061453ms","remote":"127.0.0.1:53656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.222\" "} {"level":"warn","ts":"2026-02-19T02:28:32.672266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.327256ms","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-19T02:28:32.672299Z","caller":"traceutil/trace.go:171","msg":"trace[1959793396] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:802; }","duration":"751.397787ms","start":"2026-02-19T02:28:31.920892Z","end":"2026-02-19T02:28:32.67229Z","steps":["trace[1959793396] 'agreement among raft nodes before linearized reading' (duration: 751.278415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:32.672402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:31.920871Z","time spent":"751.52223ms","remote":"127.0.0.1:53882","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-19T02:28:32.672484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:31.166141Z","time spent":"1.504968924s","remote":"127.0.0.1:53882","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-19T02:28:32.88233Z","caller":"traceutil/trace.go:171","msg":"trace[1636435247] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"205.696438ms","start":"2026-02-19T02:28:32.676605Z","end":"2026-02-19T02:28:32.882301Z","steps":["trace[1636435247] 'process raft request' (duration: 205.34653ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:32.882405Z","caller":"traceutil/trace.go:171","msg":"trace[57575138] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"205.681127ms","start":"2026-02-19T02:28:32.676683Z","end":"2026-02-19T02:28:32.882364Z","steps":["trace[57575138] 'process raft request' (duration: 205.428821ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:32.890291Z","caller":"traceutil/trace.go:171","msg":"trace[555742743] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"180.822568ms","start":"2026-02-19T02:28:32.709447Z","end":"2026-02-19T02:28:32.890269Z","steps":["trace[555742743] 'process raft request' (duration: 180.703875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:41.670669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.741806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:28:41.670773Z","caller":"traceutil/trace.go:171","msg":"trace[1276420719] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"292.028925ms","start":"2026-02-19T02:28:41.378724Z","end":"2026-02-19T02:28:41.670753Z","steps":["trace[1276420719] 'process raft request' (duration: 105.103485ms)","trace[1276420719] 'compare' (duration: 186.643854ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:28:42.292569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.47287ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:28:42.292696Z","caller":"traceutil/trace.go:171","msg":"trace[1518593761] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"617.86117ms","start":"2026-02-19T02:28:41.674812Z","end":"2026-02-19T02:28:42.292673Z","steps":["trace[1518593761] 'process raft request' (duration: 272.172575ms)","trace[1518593761] 'compare' (duration: 345.208934ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:28:42.292763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:41.674777Z","time spent":"617.956972ms","remote":"127.0.0.1:53688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58503,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:28:42.320514Z","caller":"traceutil/trace.go:171","msg":"trace[40210766] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"596.110734ms","start":"2026-02-19T02:28:41.724374Z","end":"2026-02-19T02:28:42.320485Z","steps":["trace[40210766] 'process raft request' (duration: 595.870928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:42.320622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:41.724354Z","time spent":"596.221336ms","remote":"127.0.0.1:53896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1286,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T02:28:42.320485Z","caller":"traceutil/trace.go:171","msg":"trace[1319192733] linearizableReadLoop","detail":"{readStateIndex:881; appliedIndex:878; }","duration":"518.820264ms","start":"2026-02-19T02:28:41.801635Z","end":"2026-02-19T02:28:42.320455Z","steps":["trace[1319192733] 'read index received' (duration: 145.376543ms)","trace[1319192733] 'applied index is now lower than readState.Index' (duration: 373.442231ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:28:42.320722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.05113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T02:28:42.320783Z","caller":"traceutil/trace.go:171","msg":"trace[686244809] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:846; }","duration":"519.186722ms","start":"2026-02-19T02:28:41.801583Z","end":"2026-02-19T02:28:42.32077Z","steps":["trace[686244809] 'agreement among raft nodes before linearized reading' (duration: 518.975327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:42.320842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:41.801562Z","time spent":"519.268315ms","remote":"127.0.0.1:53954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-02-19T02:28:42.320933Z","caller":"traceutil/trace.go:171","msg":"trace[148473265] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"596.38221ms","start":"2026-02-19T02:28:41.724452Z","end":"2026-02-19T02:28:42.320834Z","steps":["trace[148473265] 'process raft request' (duration: 595.94845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:42.321128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:41.724438Z","time spent":"596.593544ms","remote":"127.0.0.1:53794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":748,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T02:28:42.328522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.295989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:28:42.32859Z","caller":"traceutil/trace.go:171","msg":"trace[1713188690] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:847; }","duration":"456.402822ms","start":"2026-02-19T02:28:41.872171Z","end":"2026-02-19T02:28:42.328574Z","steps":["trace[1713188690] 'agreement among raft nodes before linearized reading' (duration: 456.265389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:42.328653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:41.872157Z","time spent":"456.485764ms","remote":"127.0.0.1:51200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-19T02:28:42.328804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.58702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-19T02:28:42.329317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.840662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.222\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-19T02:28:42.32943Z","caller":"traceutil/trace.go:171","msg":"trace[1296024186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:847; }","duration":"176.665242ms","start":"2026-02-19T02:28:42.152192Z","end":"2026-02-19T02:28:42.328857Z","steps":["trace[1296024186] 'agreement among raft nodes before linearized reading' (duration: 176.361935ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:42.32953Z","caller":"traceutil/trace.go:171","msg":"trace[506245958] range","detail":"{range_begin:/registry/masterleases/199.204.45.222; range_end:; response_count:1; response_revision:847; }","duration":"246.374054ms","start":"2026-02-19T02:28:42.082986Z","end":"2026-02-19T02:28:42.32936Z","steps":["trace[506245958] 'agreement among raft nodes before linearized reading' (duration: 245.76745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:42.598048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.168162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:28:42.598211Z","caller":"traceutil/trace.go:171","msg":"trace[1151266403] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"229.607543ms","start":"2026-02-19T02:28:42.368576Z","end":"2026-02-19T02:28:42.598184Z","steps":["trace[1151266403] 'process raft request' (duration: 34.119624ms)","trace[1151266403] 'compare' (duration: 195.013968ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:28:42.611393Z","caller":"traceutil/trace.go:171","msg":"trace[888133867] linearizableReadLoop","detail":"{readStateIndex:890; appliedIndex:888; }","duration":"128.92339ms","start":"2026-02-19T02:28:42.482451Z","end":"2026-02-19T02:28:42.611374Z","steps":["trace[888133867] 'read index received' (duration: 128.652114ms)","trace[888133867] 'applied index is now lower than readState.Index' (duration: 270.076µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:28:42.611522Z","caller":"traceutil/trace.go:171","msg":"trace[798303069] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"209.939355ms","start":"2026-02-19T02:28:42.401569Z","end":"2026-02-19T02:28:42.611508Z","steps":["trace[798303069] 'process raft request' (duration: 209.741711ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:42.611635Z","caller":"traceutil/trace.go:171","msg":"trace[479778291] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"210.714464ms","start":"2026-02-19T02:28:42.400893Z","end":"2026-02-19T02:28:42.611607Z","steps":["trace[479778291] 'process raft request' (duration: 210.280804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:42.611947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.484253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T02:28:42.612411Z","caller":"traceutil/trace.go:171","msg":"trace[847841614] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:854; }","duration":"129.843541ms","start":"2026-02-19T02:28:42.482417Z","end":"2026-02-19T02:28:42.61226Z","steps":["trace[847841614] 'agreement among raft nodes before linearized reading' (duration: 129.479552ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:42.775118Z","caller":"traceutil/trace.go:171","msg":"trace[1659235505] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"110.786788ms","start":"2026-02-19T02:28:42.664306Z","end":"2026-02-19T02:28:42.775092Z","steps":["trace[1659235505] 'process raft request' (duration: 110.647335ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:42.786054Z","caller":"traceutil/trace.go:171","msg":"trace[698705745] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"107.507642ms","start":"2026-02-19T02:28:42.67853Z","end":"2026-02-19T02:28:42.786038Z","steps":["trace[698705745] 'process raft request' (duration: 107.276386ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:42.786158Z","caller":"traceutil/trace.go:171","msg":"trace[86469223] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"107.535362ms","start":"2026-02-19T02:28:42.678614Z","end":"2026-02-19T02:28:42.78615Z","steps":["trace[86469223] 'process raft request' (duration: 107.324387ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:42.786467Z","caller":"traceutil/trace.go:171","msg":"trace[1036211929] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"104.090482ms","start":"2026-02-19T02:28:42.682356Z","end":"2026-02-19T02:28:42.786446Z","steps":["trace[1036211929] 'process raft request' (duration: 103.651502ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:42.78663Z","caller":"traceutil/trace.go:171","msg":"trace[1281866744] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"103.494679ms","start":"2026-02-19T02:28:42.683113Z","end":"2026-02-19T02:28:42.786607Z","steps":["trace[1281866744] 'process raft request' (duration: 102.958626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:44.933213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.722423ms","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-19T02:28:44.933301Z","caller":"traceutil/trace.go:171","msg":"trace[1490247075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:886; }","duration":"118.856797ms","start":"2026-02-19T02:28:44.814427Z","end":"2026-02-19T02:28:44.933284Z","steps":["trace[1490247075] 'range keys from in-memory index tree' (duration: 118.531889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:44.933392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.831937ms","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-19T02:28:44.933476Z","caller":"traceutil/trace.go:171","msg":"trace[1019019024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:886; }","duration":"118.956388ms","start":"2026-02-19T02:28:44.814495Z","end":"2026-02-19T02:28:44.933451Z","steps":["trace[1019019024] 'range keys from in-memory index tree' (duration: 118.619061ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:45.043211Z","caller":"traceutil/trace.go:171","msg":"trace[1101313920] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"105.171318ms","start":"2026-02-19T02:28:44.938012Z","end":"2026-02-19T02:28:45.043184Z","steps":["trace[1101313920] 'process raft request' (duration: 105.032265ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:45.045002Z","caller":"traceutil/trace.go:171","msg":"trace[279172492] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:922; }","duration":"103.837196ms","start":"2026-02-19T02:28:44.941147Z","end":"2026-02-19T02:28:45.044984Z","steps":["trace[279172492] 'read index received' (duration: 101.910322ms)","trace[279172492] 'applied index is now lower than readState.Index' (duration: 1.926244ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:28:45.045099Z","caller":"traceutil/trace.go:171","msg":"trace[76565311] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"105.927505ms","start":"2026-02-19T02:28:44.939137Z","end":"2026-02-19T02:28:45.045064Z","steps":["trace[76565311] 'process raft request' (duration: 105.75192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:45.04517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.995951ms","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-19T02:28:45.045191Z","caller":"traceutil/trace.go:171","msg":"trace[403323545] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:888; }","duration":"104.057413ms","start":"2026-02-19T02:28:44.941128Z","end":"2026-02-19T02:28:45.045185Z","steps":["trace[403323545] 'agreement among raft nodes before linearized reading' (duration: 103.95024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:45.045196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.791536ms","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-19T02:28:45.045281Z","caller":"traceutil/trace.go:171","msg":"trace[491922125] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:888; }","duration":"100.891478ms","start":"2026-02-19T02:28:44.944373Z","end":"2026-02-19T02:28:45.045265Z","steps":["trace[491922125] 'agreement among raft nodes before linearized reading' (duration: 100.726374ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:57.336697Z","caller":"traceutil/trace.go:171","msg":"trace[1231360301] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:956; }","duration":"311.910419ms","start":"2026-02-19T02:28:57.024765Z","end":"2026-02-19T02:28:57.336676Z","steps":["trace[1231360301] 'read index received' (duration: 311.788116ms)","trace[1231360301] 'applied index is now lower than readState.Index' (duration: 121.113µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:28:57.336804Z","caller":"traceutil/trace.go:171","msg":"trace[149120364] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"840.334717ms","start":"2026-02-19T02:28:56.496463Z","end":"2026-02-19T02:28:57.336797Z","steps":["trace[149120364] 'process raft request' (duration: 840.04898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:57.336934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:56.496454Z","time spent":"840.373967ms","remote":"127.0.0.1:53882","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-19T02:28:57.337163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.854464ms","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-19T02:28:57.337268Z","caller":"traceutil/trace.go:171","msg":"trace[1735677792] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:919; }","duration":"149.010568ms","start":"2026-02-19T02:28:57.18823Z","end":"2026-02-19T02:28:57.337241Z","steps":["trace[1735677792] 'agreement among raft nodes before linearized reading' (duration: 148.736952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:57.33734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.446204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-19T02:28:57.337365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.142054ms","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-19T02:28:57.337438Z","caller":"traceutil/trace.go:171","msg":"trace[1100611403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:919; }","duration":"154.608098ms","start":"2026-02-19T02:28:57.182807Z","end":"2026-02-19T02:28:57.337415Z","steps":["trace[1100611403] 'agreement among raft nodes before linearized reading' (duration: 154.328941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:57.337306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.464791ms","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-19T02:28:57.337555Z","caller":"traceutil/trace.go:171","msg":"trace[135775039] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:919; }","duration":"312.80308ms","start":"2026-02-19T02:28:57.024724Z","end":"2026-02-19T02:28:57.337527Z","steps":["trace[135775039] 'agreement among raft nodes before linearized reading' (duration: 312.442451ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:57.337473Z","caller":"traceutil/trace.go:171","msg":"trace[212745729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:919; }","duration":"150.317477ms","start":"2026-02-19T02:28:57.187111Z","end":"2026-02-19T02:28:57.337429Z","steps":["trace[212745729] 'agreement among raft nodes before linearized reading' (duration: 149.98994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:57.337671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:57.024703Z","time spent":"312.925683ms","remote":"127.0.0.1:54082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-02-19T02:28:57.338422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.682497ms","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-19T02:28:57.338483Z","caller":"traceutil/trace.go:171","msg":"trace[649110269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:919; }","duration":"156.805259ms","start":"2026-02-19T02:28:57.181664Z","end":"2026-02-19T02:28:57.338469Z","steps":["trace[649110269] 'agreement among raft nodes before linearized reading' (duration: 155.284243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:58.236088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:57.90674Z","time spent":"329.341033ms","remote":"127.0.0.1:43790","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-19T02:28:58.23664Z","caller":"traceutil/trace.go:171","msg":"trace[1715755939] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"894.463596ms","start":"2026-02-19T02:28:57.342153Z","end":"2026-02-19T02:28:58.236617Z","steps":["trace[1715755939] 'process raft request' (duration: 867.919729ms)","trace[1715755939] 'compare' (duration: 26.006224ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:28:58.236763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:57.342135Z","time spent":"894.549128ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:28:58.241828Z","caller":"traceutil/trace.go:171","msg":"trace[537618120] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"898.945421ms","start":"2026-02-19T02:28:57.342846Z","end":"2026-02-19T02:28:58.241791Z","steps":["trace[537618120] 'process raft request' (duration: 898.770477ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:28:58.241938Z","caller":"traceutil/trace.go:171","msg":"trace[1866008550] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"898.941201ms","start":"2026-02-19T02:28:57.342956Z","end":"2026-02-19T02:28:58.241898Z","steps":["trace[1866008550] 'process raft request' (duration: 898.724006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:58.242131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:57.342839Z","time spent":"899.158666ms","remote":"127.0.0.1:53882","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-19T02:28:58.242173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:57.34295Z","time spent":"899.119095ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:28:58.242659Z","caller":"traceutil/trace.go:171","msg":"trace[1784587312] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"900.240621ms","start":"2026-02-19T02:28:57.342328Z","end":"2026-02-19T02:28:58.242569Z","steps":["trace[1784587312] 'process raft request' (duration: 899.175656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:28:58.242775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:28:57.3423Z","time spent":"900.414496ms","remote":"127.0.0.1:53882","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-19T02:28:58.244242Z","caller":"traceutil/trace.go:171","msg":"trace[1184266713] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:957; }","duration":"210.580891ms","start":"2026-02-19T02:28:58.033637Z","end":"2026-02-19T02:28:58.244218Z","steps":["trace[1184266713] 'read index received' (duration: 176.448927ms)","trace[1184266713] 'applied index is now lower than readState.Index' (duration: 34.130654ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:28:58.244523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.874348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T02:28:58.244559Z","caller":"traceutil/trace.go:171","msg":"trace[1722929329] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:923; }","duration":"210.95415ms","start":"2026-02-19T02:28:58.033594Z","end":"2026-02-19T02:28:58.244549Z","steps":["trace[1722929329] 'agreement among raft nodes before linearized reading' (duration: 210.877828ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:29:10.424994Z","caller":"traceutil/trace.go:171","msg":"trace[1421108237] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"112.200871ms","start":"2026-02-19T02:29:10.312771Z","end":"2026-02-19T02:29:10.424972Z","steps":["trace[1421108237] 'process raft request' (duration: 112.040378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:13.023594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048853859s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:29:13.023703Z","caller":"traceutil/trace.go:171","msg":"trace[1870510589] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1000; }","duration":"939.870954ms","start":"2026-02-19T02:29:12.083815Z","end":"2026-02-19T02:29:13.023686Z","steps":["trace[1870510589] 'read index received' (duration: 54.521µs)","trace[1870510589] 'applied index is now lower than readState.Index' (duration: 939.815353ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:29:13.023819Z","caller":"traceutil/trace.go:171","msg":"trace[1960169887] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"1.129109837s","start":"2026-02-19T02:29:11.894698Z","end":"2026-02-19T02:29:13.023808Z","steps":["trace[1960169887] 'process raft request' (duration: 79.988392ms)","trace[1960169887] 'compare' (duration: 1.048664725s)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:29:13.023876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:11.894682Z","time spent":"1.129170218s","remote":"127.0.0.1:53702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T02:29:13.024487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.669702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.222\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-19T02:29:13.024537Z","caller":"traceutil/trace.go:171","msg":"trace[1083532072] range","detail":"{range_begin:/registry/masterleases/199.204.45.222; range_end:; response_count:1; response_revision:959; }","duration":"940.726073ms","start":"2026-02-19T02:29:12.0838Z","end":"2026-02-19T02:29:13.024526Z","steps":["trace[1083532072] 'agreement among raft nodes before linearized reading' (duration: 940.470707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:13.024569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:12.083741Z","time spent":"940.820966ms","remote":"127.0.0.1:53656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.222\" "} {"level":"warn","ts":"2026-02-19T02:29:13.024782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.64978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:29:13.02483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.516598ms","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-19T02:29:13.024855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.258508ms","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-19T02:29:13.024855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.871942ms","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-19T02:29:13.024884Z","caller":"traceutil/trace.go:171","msg":"trace[113096236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:959; }","duration":"750.788342ms","start":"2026-02-19T02:29:12.27407Z","end":"2026-02-19T02:29:13.024858Z","steps":["trace[113096236] 'agreement among raft nodes before linearized reading' (duration: 750.630019ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:29:13.02492Z","caller":"traceutil/trace.go:171","msg":"trace[914099297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:959; }","duration":"551.370531ms","start":"2026-02-19T02:29:12.473534Z","end":"2026-02-19T02:29:13.024905Z","steps":["trace[914099297] 'agreement among raft nodes before linearized reading' (duration: 551.173376ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:29:13.024917Z","caller":"traceutil/trace.go:171","msg":"trace[7405408] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:959; }","duration":"595.603051ms","start":"2026-02-19T02:29:12.429262Z","end":"2026-02-19T02:29:13.024866Z","steps":["trace[7405408] 'agreement among raft nodes before linearized reading' (duration: 595.474618ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:29:13.024939Z","caller":"traceutil/trace.go:171","msg":"trace[1050048213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:959; }","duration":"526.982114ms","start":"2026-02-19T02:29:12.497934Z","end":"2026-02-19T02:29:13.024916Z","steps":["trace[1050048213] 'agreement among raft nodes before linearized reading' (duration: 526.763459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:13.024961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:12.274053Z","time spent":"750.879875ms","remote":"127.0.0.1:53650","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-19T02:29:13.025009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:12.497921Z","time spent":"527.076887ms","remote":"127.0.0.1:53882","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-19T02:29:13.024965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:12.473514Z","time spent":"551.444693ms","remote":"127.0.0.1:53882","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-19T02:29:13.025241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.889733ms","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-19T02:29:13.025389Z","caller":"traceutil/trace.go:171","msg":"trace[1628879004] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:959; }","duration":"527.058667ms","start":"2026-02-19T02:29:12.498308Z","end":"2026-02-19T02:29:13.025367Z","steps":["trace[1628879004] 'agreement among raft nodes before linearized reading' (duration: 526.502133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:13.026088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:12.498299Z","time spent":"527.768673ms","remote":"127.0.0.1:53882","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-19T02:29:13.025558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:12.429239Z","time spent":"596.291707ms","remote":"127.0.0.1:53882","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-19T02:29:13.782392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.666653ms","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-19T02:29:13.782537Z","caller":"traceutil/trace.go:171","msg":"trace[1095400202] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:959; }","duration":"372.819157ms","start":"2026-02-19T02:29:13.409693Z","end":"2026-02-19T02:29:13.782512Z","steps":["trace[1095400202] 'range keys from in-memory index tree' (duration: 372.648133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:13.782811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.74323ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-19T02:29:13.783046Z","caller":"traceutil/trace.go:171","msg":"trace[49427672] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"757.099819ms","start":"2026-02-19T02:29:13.025927Z","end":"2026-02-19T02:29:13.783026Z","steps":["trace[49427672] 'read index received' (duration: 196.152675ms)","trace[49427672] 'applied index is now lower than readState.Index' (duration: 560.945344ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:29:13.783129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:13.025454Z","time spent":"757.671763ms","remote":"127.0.0.1:53656","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-19T02:29:13.783267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.327844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:29:13.783336Z","caller":"traceutil/trace.go:171","msg":"trace[1182731704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:959; }","duration":"757.415317ms","start":"2026-02-19T02:29:13.025883Z","end":"2026-02-19T02:29:13.783298Z","steps":["trace[1182731704] 'agreement among raft nodes before linearized reading' (duration: 757.249773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:13.78338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:13.025865Z","time spent":"757.505899ms","remote":"127.0.0.1:53652","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-19T02:29:13.820287Z","caller":"traceutil/trace.go:171","msg":"trace[292998752] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"789.335109ms","start":"2026-02-19T02:29:13.030927Z","end":"2026-02-19T02:29:13.820262Z","steps":["trace[292998752] 'process raft request' (duration: 789.279618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:13.820566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:13.030919Z","time spent":"789.464082ms","remote":"127.0.0.1:53882","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-19T02:29:13.820821Z","caller":"traceutil/trace.go:171","msg":"trace[284088561] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"791.44553ms","start":"2026-02-19T02:29:13.029355Z","end":"2026-02-19T02:29:13.820801Z","steps":["trace[284088561] 'process raft request' (duration: 790.703662ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:29:13.820773Z","caller":"traceutil/trace.go:171","msg":"trace[1657218046] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"791.90247ms","start":"2026-02-19T02:29:13.028834Z","end":"2026-02-19T02:29:13.820736Z","steps":["trace[1657218046] 'process raft request' (duration: 790.949008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:13.821176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:13.028818Z","time spent":"792.192697ms","remote":"127.0.0.1:53882","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-19T02:29:13.820916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:13.029343Z","time spent":"791.529421ms","remote":"127.0.0.1:53882","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-19T02:29:13.821321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.313368ms","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-19T02:29:13.821425Z","caller":"traceutil/trace.go:171","msg":"trace[779417278] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:963; }","duration":"385.507482ms","start":"2026-02-19T02:29:13.435902Z","end":"2026-02-19T02:29:13.82141Z","steps":["trace[779417278] 'agreement among raft nodes before linearized reading' (duration: 385.092822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:13.821506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:13.435879Z","time spent":"385.614555ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-19T02:29:13.821783Z","caller":"traceutil/trace.go:171","msg":"trace[1933036877] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"791.490611ms","start":"2026-02-19T02:29:13.029257Z","end":"2026-02-19T02:29:13.820747Z","steps":["trace[1933036877] 'process raft request' (duration: 790.743983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:13.821965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:13.029241Z","time spent":"792.655337ms","remote":"127.0.0.1:53882","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-19T02:29:13.987434Z","caller":"traceutil/trace.go:171","msg":"trace[1496543777] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"129.278649ms","start":"2026-02-19T02:29:13.858123Z","end":"2026-02-19T02:29:13.987402Z","steps":["trace[1496543777] 'read index received' (duration: 46.517142ms)","trace[1496543777] 'applied index is now lower than readState.Index' (duration: 82.749476ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:29:13.98768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.542474ms","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-19T02:29:13.987812Z","caller":"traceutil/trace.go:171","msg":"trace[298002822] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:965; }","duration":"129.732979ms","start":"2026-02-19T02:29:13.858061Z","end":"2026-02-19T02:29:13.987794Z","steps":["trace[298002822] 'agreement among raft nodes before linearized reading' (duration: 129.460022ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:29:13.988109Z","caller":"traceutil/trace.go:171","msg":"trace[1155337761] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"159.80701ms","start":"2026-02-19T02:29:13.82829Z","end":"2026-02-19T02:29:13.988097Z","steps":["trace[1155337761] 'process raft request' (duration: 76.340617ms)","trace[1155337761] 'compare' (duration: 82.663724ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:29:15.144583Z","caller":"traceutil/trace.go:171","msg":"trace[1058978863] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1009; }","duration":"451.872826ms","start":"2026-02-19T02:29:14.692692Z","end":"2026-02-19T02:29:15.144564Z","steps":["trace[1058978863] 'read index received' (duration: 397.846089ms)","trace[1058978863] 'applied index is now lower than readState.Index' (duration: 54.025617ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:29:15.144586Z","caller":"traceutil/trace.go:171","msg":"trace[1583098617] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"534.332516ms","start":"2026-02-19T02:29:14.610233Z","end":"2026-02-19T02:29:15.144566Z","steps":["trace[1583098617] 'process raft request' (duration: 480.35729ms)","trace[1583098617] 'compare' (duration: 53.851553ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:29:15.144704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:14.610207Z","time spent":"534.430859ms","remote":"127.0.0.1:53882","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-19T02:29:15.144718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.015049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4742"} {"level":"info","ts":"2026-02-19T02:29:15.144742Z","caller":"traceutil/trace.go:171","msg":"trace[762488368] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:967; }","duration":"452.05039ms","start":"2026-02-19T02:29:14.692686Z","end":"2026-02-19T02:29:15.144736Z","steps":["trace[762488368] 'agreement among raft nodes before linearized reading' (duration: 451.941247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:15.144773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:14.692671Z","time spent":"452.098702ms","remote":"127.0.0.1:53804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4765,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-19T02:29:16.003738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.068061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:29:16.003794Z","caller":"traceutil/trace.go:171","msg":"trace[378534574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:967; }","duration":"730.170463ms","start":"2026-02-19T02:29:15.273611Z","end":"2026-02-19T02:29:16.003781Z","steps":["trace[378534574] 'range keys from in-memory index tree' (duration: 730.00415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:16.00382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:15.273591Z","time spent":"730.223845ms","remote":"127.0.0.1:53650","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-19T02:29:16.004124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.024719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:29:16.004155Z","caller":"traceutil/trace.go:171","msg":"trace[1505319712] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:967; }","duration":"574.082621ms","start":"2026-02-19T02:29:15.430064Z","end":"2026-02-19T02:29:16.004146Z","steps":["trace[1505319712] 'range keys from in-memory index tree' (duration: 573.960628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:16.004173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:15.430048Z","time spent":"574.120561ms","remote":"127.0.0.1:54000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" "} {"level":"info","ts":"2026-02-19T02:29:16.004801Z","caller":"traceutil/trace.go:171","msg":"trace[1674104290] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1010; }","duration":"404.727668ms","start":"2026-02-19T02:29:15.600057Z","end":"2026-02-19T02:29:16.004784Z","steps":["trace[1674104290] 'read index received' (duration: 361.438251ms)","trace[1674104290] 'applied index is now lower than readState.Index' (duration: 43.288017ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:29:16.00484Z","caller":"traceutil/trace.go:171","msg":"trace[1560362815] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"568.278226ms","start":"2026-02-19T02:29:15.436533Z","end":"2026-02-19T02:29:16.004811Z","steps":["trace[1560362815] 'process raft request' (duration: 524.980179ms)","trace[1560362815] 'compare' (duration: 41.898475ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:29:16.004961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:15.436499Z","time spent":"568.398008ms","remote":"127.0.0.1:53702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T02:29:16.004987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.916213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-19T02:29:16.005007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.155879ms","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-19T02:29:16.005027Z","caller":"traceutil/trace.go:171","msg":"trace[1194138131] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:968; }","duration":"179.209731ms","start":"2026-02-19T02:29:15.825812Z","end":"2026-02-19T02:29:16.005022Z","steps":["trace[1194138131] 'agreement among raft nodes before linearized reading' (duration: 179.091588ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:29:16.005028Z","caller":"traceutil/trace.go:171","msg":"trace[1946284761] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:968; }","duration":"405.005484ms","start":"2026-02-19T02:29:15.600007Z","end":"2026-02-19T02:29:16.005012Z","steps":["trace[1946284761] 'agreement among raft nodes before linearized reading' (duration: 404.875971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:16.005053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:15.599986Z","time spent":"405.059036ms","remote":"127.0.0.1:54156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-02-19T02:29:16.005109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.302858ms","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-19T02:29:16.005158Z","caller":"traceutil/trace.go:171","msg":"trace[788988676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:968; }","duration":"178.37641ms","start":"2026-02-19T02:29:15.82677Z","end":"2026-02-19T02:29:16.005147Z","steps":["trace[788988676] 'agreement among raft nodes before linearized reading' (duration: 178.259508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:16.005287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.135674ms","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-19T02:29:16.005316Z","caller":"traceutil/trace.go:171","msg":"trace[1462176932] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:968; }","duration":"178.183516ms","start":"2026-02-19T02:29:15.827124Z","end":"2026-02-19T02:29:16.005307Z","steps":["trace[1462176932] 'agreement among raft nodes before linearized reading' (duration: 177.894949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:16.005391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.296976ms","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-19T02:29:16.005431Z","caller":"traceutil/trace.go:171","msg":"trace[1362994686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:968; }","duration":"177.336617ms","start":"2026-02-19T02:29:15.828085Z","end":"2026-02-19T02:29:16.005421Z","steps":["trace[1362994686] 'agreement among raft nodes before linearized reading' (duration: 176.899257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:16.28129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.484594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:29:16.281475Z","caller":"traceutil/trace.go:171","msg":"trace[1048565064] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"270.786363ms","start":"2026-02-19T02:29:16.010671Z","end":"2026-02-19T02:29:16.281458Z","steps":["trace[1048565064] 'process raft request' (duration: 147.079723ms)","trace[1048565064] 'compare' (duration: 123.381202ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:29:16.281579Z","caller":"traceutil/trace.go:171","msg":"trace[892812607] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"270.598648ms","start":"2026-02-19T02:29:16.010954Z","end":"2026-02-19T02:29:16.281553Z","steps":["trace[892812607] 'process raft request' (duration: 270.441634ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:29:16.2819Z","caller":"traceutil/trace.go:171","msg":"trace[736713297] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"270.922076ms","start":"2026-02-19T02:29:16.010969Z","end":"2026-02-19T02:29:16.281891Z","steps":["trace[736713297] 'process raft request' (duration: 270.724871ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:29:16.281999Z","caller":"traceutil/trace.go:171","msg":"trace[71069422] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"270.604208ms","start":"2026-02-19T02:29:16.011359Z","end":"2026-02-19T02:29:16.281963Z","steps":["trace[71069422] 'process raft request' (duration: 270.378943ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:29:16.282168Z","caller":"traceutil/trace.go:171","msg":"trace[2037544870] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"270.605138ms","start":"2026-02-19T02:29:16.011547Z","end":"2026-02-19T02:29:16.282152Z","steps":["trace[2037544870] 'process raft request' (duration: 270.367392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:18.208225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.018986ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-19T02:29:18.208591Z","caller":"traceutil/trace.go:171","msg":"trace[1282173692] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"116.050552ms","start":"2026-02-19T02:29:18.092522Z","end":"2026-02-19T02:29:18.208573Z","steps":["trace[1282173692] 'read index received' (duration: 57.111µs)","trace[1282173692] 'applied index is now lower than readState.Index' (duration: 115.991761ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:29:18.208748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.225885ms","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-19T02:29:18.208774Z","caller":"traceutil/trace.go:171","msg":"trace[912986651] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:975; }","duration":"116.301657ms","start":"2026-02-19T02:29:18.092464Z","end":"2026-02-19T02:29:18.208766Z","steps":["trace[912986651] 'agreement among raft nodes before linearized reading' (duration: 116.158534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:18.612145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.607134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:29:18.612336Z","caller":"traceutil/trace.go:171","msg":"trace[282041609] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"321.431151ms","start":"2026-02-19T02:29:18.290824Z","end":"2026-02-19T02:29:18.612256Z","steps":["trace[282041609] 'process raft request' (duration: 36.629193ms)","trace[282041609] 'compare' (duration: 284.482551ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:29:18.612554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:18.290807Z","time spent":"321.650846ms","remote":"127.0.0.1:53882","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-19T02:29:18.663456Z","caller":"traceutil/trace.go:171","msg":"trace[720673009] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"372.257323ms","start":"2026-02-19T02:29:18.291179Z","end":"2026-02-19T02:29:18.663437Z","steps":["trace[720673009] 'process raft request' (duration: 372.228573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:18.663578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:18.291151Z","time spent":"372.364526ms","remote":"127.0.0.1:53882","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-19T02:29:18.663851Z","caller":"traceutil/trace.go:171","msg":"trace[1434059951] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"372.767206ms","start":"2026-02-19T02:29:18.291077Z","end":"2026-02-19T02:29:18.663844Z","steps":["trace[1434059951] 'process raft request' (duration: 372.201533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:18.663898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:18.291055Z","time spent":"372.819336ms","remote":"127.0.0.1:53882","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-19T02:29:18.663989Z","caller":"traceutil/trace.go:171","msg":"trace[613473955] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"372.868328ms","start":"2026-02-19T02:29:18.291114Z","end":"2026-02-19T02:29:18.663983Z","steps":["trace[613473955] 'process raft request' (duration: 372.270414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:18.664058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:18.291096Z","time spent":"372.911468ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:29:18.687934Z","caller":"traceutil/trace.go:171","msg":"trace[1452478011] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"306.600816ms","start":"2026-02-19T02:29:18.381317Z","end":"2026-02-19T02:29:18.687918Z","steps":["trace[1452478011] 'process raft request' (duration: 306.528504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:18.688035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:18.381284Z","time spent":"306.705819ms","remote":"127.0.0.1:53702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":786,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T02:29:19.593364Z","caller":"traceutil/trace.go:171","msg":"trace[1161397271] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"663.168244ms","start":"2026-02-19T02:29:18.930174Z","end":"2026-02-19T02:29:19.593343Z","steps":["trace[1161397271] 'process raft request' (duration: 593.326959ms)","trace[1161397271] 'compare' (duration: 69.771633ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:29:19.593474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:18.930143Z","time spent":"663.279006ms","remote":"127.0.0.1:53882","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-19T02:29:20.804587Z","caller":"traceutil/trace.go:171","msg":"trace[123584141] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"131.08481ms","start":"2026-02-19T02:29:20.673488Z","end":"2026-02-19T02:29:20.804572Z","steps":["trace[123584141] 'process raft request' (duration: 131.019908ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:29:20.804642Z","caller":"traceutil/trace.go:171","msg":"trace[1458523356] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"131.252194ms","start":"2026-02-19T02:29:20.673356Z","end":"2026-02-19T02:29:20.804608Z","steps":["trace[1458523356] 'process raft request' (duration: 131.077579ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:29:24.125581Z","caller":"traceutil/trace.go:171","msg":"trace[880019868] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"344.14905ms","start":"2026-02-19T02:29:23.781396Z","end":"2026-02-19T02:29:24.125545Z","steps":["trace[880019868] 'process raft request' (duration: 343.871544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:24.125784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:23.781374Z","time spent":"344.309233ms","remote":"127.0.0.1:53702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T02:29:26.414485Z","caller":"traceutil/trace.go:171","msg":"trace[1233392000] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"128.098231ms","start":"2026-02-19T02:29:26.286366Z","end":"2026-02-19T02:29:26.414464Z","steps":["trace[1233392000] 'process raft request' (duration: 127.989249ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:29:34.56487Z","caller":"traceutil/trace.go:171","msg":"trace[1408536187] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1124; }","duration":"112.711383ms","start":"2026-02-19T02:29:34.452138Z","end":"2026-02-19T02:29:34.564849Z","steps":["trace[1408536187] 'read index received' (duration: 112.517119ms)","trace[1408536187] 'applied index is now lower than readState.Index' (duration: 193.154µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:29:34.564941Z","caller":"traceutil/trace.go:171","msg":"trace[887611808] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"268.275123ms","start":"2026-02-19T02:29:34.296636Z","end":"2026-02-19T02:29:34.564911Z","steps":["trace[887611808] 'process raft request' (duration: 268.091268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:34.565084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.934299ms","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-19T02:29:34.56512Z","caller":"traceutil/trace.go:171","msg":"trace[166509245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1078; }","duration":"113.018881ms","start":"2026-02-19T02:29:34.45209Z","end":"2026-02-19T02:29:34.565109Z","steps":["trace[166509245] 'agreement among raft nodes before linearized reading' (duration: 112.876218ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:29:35.19856Z","caller":"traceutil/trace.go:171","msg":"trace[1031373154] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"629.17319ms","start":"2026-02-19T02:29:34.569367Z","end":"2026-02-19T02:29:35.198541Z","steps":["trace[1031373154] 'process raft request' (duration: 628.180967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:35.198674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:34.569344Z","time spent":"629.275452ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:29:35.203882Z","caller":"traceutil/trace.go:171","msg":"trace[1952186505] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1125; }","duration":"487.513832ms","start":"2026-02-19T02:29:34.716342Z","end":"2026-02-19T02:29:35.203855Z","steps":["trace[1952186505] 'read index received' (duration: 481.316287ms)","trace[1952186505] 'applied index is now lower than readState.Index' (duration: 6.196345ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:29:35.204369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.135806ms","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-19T02:29:35.204427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.153077ms","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-19T02:29:35.204444Z","caller":"traceutil/trace.go:171","msg":"trace[689935236] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"633.203703ms","start":"2026-02-19T02:29:34.571224Z","end":"2026-02-19T02:29:35.204428Z","steps":["trace[689935236] 'process raft request' (duration: 632.522097ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:29:35.204434Z","caller":"traceutil/trace.go:171","msg":"trace[166809331] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1080; }","duration":"442.252268ms","start":"2026-02-19T02:29:34.762165Z","end":"2026-02-19T02:29:35.204417Z","steps":["trace[166809331] 'agreement among raft nodes before linearized reading' (duration: 442.052854ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:29:35.20449Z","caller":"traceutil/trace.go:171","msg":"trace[1579957744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1080; }","duration":"342.24661ms","start":"2026-02-19T02:29:34.862228Z","end":"2026-02-19T02:29:35.204474Z","steps":["trace[1579957744] 'agreement among raft nodes before linearized reading' (duration: 342.011414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:35.204528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:34.571205Z","time spent":"633.280984ms","remote":"127.0.0.1:53882","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-19T02:29:35.204531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:34.862222Z","time spent":"342.300551ms","remote":"127.0.0.1:53882","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-19T02:29:35.20455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.937616ms","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-19T02:29:35.204538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:34.762145Z","time spent":"442.376462ms","remote":"127.0.0.1:53882","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-19T02:29:35.20458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.6522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-19T02:29:35.204622Z","caller":"traceutil/trace.go:171","msg":"trace[1033721276] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1080; }","duration":"343.051529ms","start":"2026-02-19T02:29:34.861557Z","end":"2026-02-19T02:29:35.204609Z","steps":["trace[1033721276] 'agreement among raft nodes before linearized reading' (duration: 342.783483ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:29:35.204657Z","caller":"traceutil/trace.go:171","msg":"trace[2064735640] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1080; }","duration":"298.781074ms","start":"2026-02-19T02:29:34.905857Z","end":"2026-02-19T02:29:35.204638Z","steps":["trace[2064735640] 'agreement among raft nodes before linearized reading' (duration: 298.546398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:35.204659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:34.861539Z","time spent":"343.110801ms","remote":"127.0.0.1:53882","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-19T02:29:35.204988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.653838ms","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-19T02:29:35.205035Z","caller":"traceutil/trace.go:171","msg":"trace[799810141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1080; }","duration":"488.745ms","start":"2026-02-19T02:29:34.716278Z","end":"2026-02-19T02:29:35.205023Z","steps":["trace[799810141] 'agreement among raft nodes before linearized reading' (duration: 488.555626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:35.205066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:29:34.716248Z","time spent":"488.810921ms","remote":"127.0.0.1:53882","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-19T02:29:39.38045Z","caller":"traceutil/trace.go:171","msg":"trace[888244198] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"102.172317ms","start":"2026-02-19T02:29:39.278234Z","end":"2026-02-19T02:29:39.380406Z","steps":["trace[888244198] 'process raft request' (duration: 101.984703ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:29:57.581293Z","caller":"traceutil/trace.go:171","msg":"trace[1186088927] linearizableReadLoop","detail":"{readStateIndex:1223; appliedIndex:1222; }","duration":"202.898272ms","start":"2026-02-19T02:29:57.378372Z","end":"2026-02-19T02:29:57.58127Z","steps":["trace[1186088927] 'read index received' (duration: 202.756789ms)","trace[1186088927] 'applied index is now lower than readState.Index' (duration: 140.113µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:29:57.581408Z","caller":"traceutil/trace.go:171","msg":"trace[1274482641] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"207.243552ms","start":"2026-02-19T02:29:57.374156Z","end":"2026-02-19T02:29:57.581399Z","steps":["trace[1274482641] 'process raft request' (duration: 206.950315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:57.581643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.866792ms","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-19T02:29:57.581718Z","caller":"traceutil/trace.go:171","msg":"trace[1154785225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1171; }","duration":"180.978034ms","start":"2026-02-19T02:29:57.400719Z","end":"2026-02-19T02:29:57.581697Z","steps":["trace[1154785225] 'agreement among raft nodes before linearized reading' (duration: 180.782829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:57.581776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.192143ms","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-19T02:29:57.581805Z","caller":"traceutil/trace.go:171","msg":"trace[1623520041] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1171; }","duration":"116.282355ms","start":"2026-02-19T02:29:57.465514Z","end":"2026-02-19T02:29:57.581796Z","steps":["trace[1623520041] 'agreement among raft nodes before linearized reading' (duration: 116.162222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:57.581906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.535206ms","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-19T02:29:57.581923Z","caller":"traceutil/trace.go:171","msg":"trace[105425390] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1171; }","duration":"203.603489ms","start":"2026-02-19T02:29:57.378313Z","end":"2026-02-19T02:29:57.581917Z","steps":["trace[105425390] 'agreement among raft nodes before linearized reading' (duration: 203.550517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:57.58204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.981924ms","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-19T02:29:57.582076Z","caller":"traceutil/trace.go:171","msg":"trace[917926020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1171; }","duration":"118.093756ms","start":"2026-02-19T02:29:57.463972Z","end":"2026-02-19T02:29:57.582065Z","steps":["trace[917926020] 'agreement among raft nodes before linearized reading' (duration: 117.952003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:29:57.582182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.313182ms","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-19T02:29:57.582207Z","caller":"traceutil/trace.go:171","msg":"trace[1975217561] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1171; }","duration":"178.359973ms","start":"2026-02-19T02:29:57.40384Z","end":"2026-02-19T02:29:57.5822Z","steps":["trace[1975217561] 'agreement among raft nodes before linearized reading' (duration: 178.301262ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:29:59.976159Z","caller":"traceutil/trace.go:171","msg":"trace[411917602] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"246.88204ms","start":"2026-02-19T02:29:59.72925Z","end":"2026-02-19T02:29:59.976132Z","steps":["trace[411917602] 'process raft request' (duration: 246.841169ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:29:59.976261Z","caller":"traceutil/trace.go:171","msg":"trace[992642387] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"247.69316ms","start":"2026-02-19T02:29:59.72854Z","end":"2026-02-19T02:29:59.976233Z","steps":["trace[992642387] 'process raft request' (duration: 247.513904ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:29:59.97621Z","caller":"traceutil/trace.go:171","msg":"trace[390790694] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"247.962795ms","start":"2026-02-19T02:29:59.728209Z","end":"2026-02-19T02:29:59.976171Z","steps":["trace[390790694] 'process raft request' (duration: 247.697109ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:30:11.797765Z","caller":"traceutil/trace.go:171","msg":"trace[1518258721] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"155.762848ms","start":"2026-02-19T02:30:11.64196Z","end":"2026-02-19T02:30:11.797723Z","steps":["trace[1518258721] 'process raft request' (duration: 155.512033ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:30:11.797644Z","caller":"traceutil/trace.go:171","msg":"trace[1521747275] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1281; }","duration":"136.311018ms","start":"2026-02-19T02:30:11.661313Z","end":"2026-02-19T02:30:11.797624Z","steps":["trace[1521747275] 'read index received' (duration: 136.120044ms)","trace[1521747275] 'applied index is now lower than readState.Index' (duration: 189.924µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:30:11.798103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.777899ms","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-19T02:30:11.798194Z","caller":"traceutil/trace.go:171","msg":"trace[27574275] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1228; }","duration":"136.919803ms","start":"2026-02-19T02:30:11.66126Z","end":"2026-02-19T02:30:11.79818Z","steps":["trace[27574275] 'agreement among raft nodes before linearized reading' (duration: 136.563404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:30:11.798127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.499975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:30:11.798388Z","caller":"traceutil/trace.go:171","msg":"trace[646613109] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1228; }","duration":"102.813562ms","start":"2026-02-19T02:30:11.695562Z","end":"2026-02-19T02:30:11.798375Z","steps":["trace[646613109] 'agreement among raft nodes before linearized reading' (duration: 102.491885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:30:16.382577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.656261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:30:16.382664Z","caller":"traceutil/trace.go:171","msg":"trace[1903920388] linearizableReadLoop","detail":"{readStateIndex:1306; appliedIndex:1305; }","duration":"108.271029ms","start":"2026-02-19T02:30:16.274381Z","end":"2026-02-19T02:30:16.382652Z","steps":["trace[1903920388] 'read index received' (duration: 1.406823ms)","trace[1903920388] 'applied index is now lower than readState.Index' (duration: 106.862866ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:30:16.382724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.350671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:30:16.382704Z","caller":"traceutil/trace.go:171","msg":"trace[43859115] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"267.077357ms","start":"2026-02-19T02:30:16.11559Z","end":"2026-02-19T02:30:16.382668Z","steps":["trace[43859115] 'process raft request' (duration: 160.263183ms)","trace[43859115] 'compare' (duration: 106.565609ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:30:16.382751Z","caller":"traceutil/trace.go:171","msg":"trace[148611428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1251; }","duration":"108.376151ms","start":"2026-02-19T02:30:16.274368Z","end":"2026-02-19T02:30:16.382744Z","steps":["trace[148611428] 'agreement among raft nodes before linearized reading' (duration: 108.31701ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:30:38.275662Z","caller":"traceutil/trace.go:171","msg":"trace[612788103] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"168.210527ms","start":"2026-02-19T02:30:38.107431Z","end":"2026-02-19T02:30:38.275642Z","steps":["trace[612788103] 'process raft request' (duration: 167.44711ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:30:40.246172Z","caller":"traceutil/trace.go:171","msg":"trace[389012642] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"135.944859ms","start":"2026-02-19T02:30:40.110194Z","end":"2026-02-19T02:30:40.246139Z","steps":["trace[389012642] 'process raft request' (duration: 135.701382ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:30:53.030079Z","caller":"traceutil/trace.go:171","msg":"trace[760145937] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"169.316879ms","start":"2026-02-19T02:30:52.860742Z","end":"2026-02-19T02:30:53.030059Z","steps":["trace[760145937] 'process raft request' (duration: 169.195416ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:32:26.998468Z","caller":"traceutil/trace.go:171","msg":"trace[1879550688] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"107.293179ms","start":"2026-02-19T02:32:26.891151Z","end":"2026-02-19T02:32:26.998444Z","steps":["trace[1879550688] 'process raft request' (duration: 107.245848ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:32:26.998657Z","caller":"traceutil/trace.go:171","msg":"trace[840196819] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"107.894523ms","start":"2026-02-19T02:32:26.890749Z","end":"2026-02-19T02:32:26.998644Z","steps":["trace[840196819] 'process raft request' (duration: 42.171518ms)","trace[840196819] 'compare' (duration: 65.348226ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:32:33.074029Z","caller":"traceutil/trace.go:171","msg":"trace[1062773678] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1911; }","duration":"168.276253ms","start":"2026-02-19T02:32:32.90568Z","end":"2026-02-19T02:32:33.073956Z","steps":["trace[1062773678] 'read index received' (duration: 167.996887ms)","trace[1062773678] 'applied index is now lower than readState.Index' (duration: 277.496µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:32:33.074084Z","caller":"traceutil/trace.go:171","msg":"trace[863079707] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"200.626132ms","start":"2026-02-19T02:32:32.873414Z","end":"2026-02-19T02:32:33.07404Z","steps":["trace[863079707] 'process raft request' (duration: 200.354646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:32:33.074294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.54603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-19T02:32:33.074411Z","caller":"traceutil/trace.go:171","msg":"trace[1408362580] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1828; }","duration":"168.750885ms","start":"2026-02-19T02:32:32.905641Z","end":"2026-02-19T02:32:33.074392Z","steps":["trace[1408362580] 'agreement among raft nodes before linearized reading' (duration: 168.457409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:32:33.074478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.331008ms","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-19T02:32:33.07454Z","caller":"traceutil/trace.go:171","msg":"trace[1624146995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1828; }","duration":"166.494672ms","start":"2026-02-19T02:32:32.908026Z","end":"2026-02-19T02:32:33.074521Z","steps":["trace[1624146995] 'agreement among raft nodes before linearized reading' (duration: 166.156874ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:32:33.224261Z","caller":"traceutil/trace.go:171","msg":"trace[1718432430] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"145.74278ms","start":"2026-02-19T02:32:33.07849Z","end":"2026-02-19T02:32:33.224233Z","steps":["trace[1718432430] 'process raft request' (duration: 121.146029ms)","trace[1718432430] 'compare' (duration: 24.484248ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:32:33.228139Z","caller":"traceutil/trace.go:171","msg":"trace[1125251212] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"149.480966ms","start":"2026-02-19T02:32:33.078635Z","end":"2026-02-19T02:32:33.228116Z","steps":["trace[1125251212] 'process raft request' (duration: 149.282552ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:32:33.228129Z","caller":"traceutil/trace.go:171","msg":"trace[1922650975] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"149.409675ms","start":"2026-02-19T02:32:33.078699Z","end":"2026-02-19T02:32:33.228108Z","steps":["trace[1922650975] 'process raft request' (duration: 149.328433ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:32:33.228445Z","caller":"traceutil/trace.go:171","msg":"trace[1625664395] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"149.536619ms","start":"2026-02-19T02:32:33.078894Z","end":"2026-02-19T02:32:33.228431Z","steps":["trace[1625664395] 'process raft request' (duration: 149.177129ms)"],"step_count":1}