{"level":"warn","ts":"2026-02-25T01:41:41.701488Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T01:41:41.701599Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-25T01:41:41.701677Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T01:41:41.701695Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-02-25T01:41:41.701759Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-25T01:41:41.705717Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-02-25T01:41:41.705877Z","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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-25T01:41:41.709981Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.874181ms"} {"level":"info","ts":"2026-02-25T01:41:41.720584Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-02-25T01:41:41.720777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T01:41:41.720934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-02-25T01:41:41.72096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T01:41:41.721024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-02-25T01:41:41.721144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-02-25T01:41:41.728234Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T01:41:41.729707Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T01:41:41.731462Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-25T01:41:41.734986Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T01:41:41.736169Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T01:41:41.736356Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T01:41:41.736448Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T01:41:41.736461Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T01:41:41.736725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-02-25T01:41:41.736804Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-02-25T01:41:41.741497Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-25T01:41:41.741604Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-02-25T01:41:41.741621Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-02-25T01:41:41.742323Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T01:41:41.742369Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T01:41:42.321959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T01:41:42.322083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T01:41:42.322156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-02-25T01:41:42.322189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-02-25T01:41:42.322288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-02-25T01:41:42.322336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-02-25T01:41:42.322358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-02-25T01:41:42.323532Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T01:41:42.323616Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T01:41:42.324271Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:41:42.32466Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T01:41:42.325259Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:41:42.325897Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T01:41:42.325945Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T01:41:42.326501Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:41:42.326659Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-02-25T01:41:42.326682Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:41:42.328567Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-25T01:41:55.832328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.433054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:41:55.832533Z","caller":"traceutil/trace.go:171","msg":"trace[485321520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:361; }","duration":"265.755322ms","start":"2026-02-25T01:41:55.566748Z","end":"2026-02-25T01:41:55.832504Z","steps":["trace[485321520] 'range keys from in-memory index tree' (duration: 265.299401ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:58.486091Z","caller":"traceutil/trace.go:171","msg":"trace[1522539977] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"108.782285ms","start":"2026-02-25T01:41:58.377274Z","end":"2026-02-25T01:41:58.486056Z","steps":["trace[1522539977] 'process raft request' (duration: 54.619863ms)","trace[1522539977] 'compare' (duration: 53.968557ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:02.487066Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724526911544490,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T01:42:02.795768Z","caller":"traceutil/trace.go:171","msg":"trace[376982925] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"808.928168ms","start":"2026-02-25T01:42:01.986814Z","end":"2026-02-25T01:42:02.795743Z","steps":["trace[376982925] 'read index received' (duration: 808.765204ms)","trace[376982925] 'applied index is now lower than readState.Index' (duration: 162.214µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:02.796093Z","caller":"traceutil/trace.go:171","msg":"trace[2077918276] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"900.702263ms","start":"2026-02-25T01:42:01.895348Z","end":"2026-02-25T01:42:02.79605Z","steps":["trace[2077918276] 'process raft request' (duration: 900.285403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:02.796347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.701454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:02.796429Z","caller":"traceutil/trace.go:171","msg":"trace[1269924264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:412; }","duration":"229.828457ms","start":"2026-02-25T01:42:02.56658Z","end":"2026-02-25T01:42:02.796408Z","steps":["trace[1269924264] 'agreement among raft nodes before linearized reading' (duration: 229.678004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:02.796711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.87694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T01:42:02.797462Z","caller":"traceutil/trace.go:171","msg":"trace[1952348043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:412; }","duration":"810.662259ms","start":"2026-02-25T01:42:01.986781Z","end":"2026-02-25T01:42:02.797443Z","steps":["trace[1952348043] 'agreement among raft nodes before linearized reading' (duration: 809.8619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:02.797616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:01.986764Z","time spent":"810.835012ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T01:42:02.798246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:01.895331Z","time spent":"900.905447ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:09.35025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724526911544532,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T01:42:09.361038Z","caller":"traceutil/trace.go:171","msg":"trace[1270841707] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"510.93835ms","start":"2026-02-25T01:42:08.850073Z","end":"2026-02-25T01:42:09.361012Z","steps":["trace[1270841707] 'read index received' (duration: 510.714624ms)","trace[1270841707] 'applied index is now lower than readState.Index' (duration: 222.626µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:09.361247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.154244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T01:42:09.361349Z","caller":"traceutil/trace.go:171","msg":"trace[527485916] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:427; }","duration":"511.310248ms","start":"2026-02-25T01:42:08.850025Z","end":"2026-02-25T01:42:09.361335Z","steps":["trace[527485916] 'agreement among raft nodes before linearized reading' (duration: 511.140214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:09.36139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:08.849997Z","time spent":"511.383669ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T01:42:09.361366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.105974ms","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-25T01:42:09.361414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.176698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T01:42:09.361475Z","caller":"traceutil/trace.go:171","msg":"trace[893946322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:427; }","duration":"365.279848ms","start":"2026-02-25T01:42:08.996165Z","end":"2026-02-25T01:42:09.361445Z","steps":["trace[893946322] 'agreement among raft nodes before linearized reading' (duration: 365.083723ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:09.361228Z","caller":"traceutil/trace.go:171","msg":"trace[1251245655] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"516.50773ms","start":"2026-02-25T01:42:08.844652Z","end":"2026-02-25T01:42:09.36116Z","steps":["trace[1251245655] 'process raft request' (duration: 516.198793ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:09.361501Z","caller":"traceutil/trace.go:171","msg":"trace[1276946423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:427; }","duration":"317.319092ms","start":"2026-02-25T01:42:09.044161Z","end":"2026-02-25T01:42:09.361481Z","steps":["trace[1276946423] 'agreement among raft nodes before linearized reading' (duration: 317.118217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:09.361548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:09.044141Z","time spent":"317.400314ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T01:42:09.361545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:08.996143Z","time spent":"365.385971ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T01:42:09.361693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:08.844636Z","time spent":"516.93128ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:14.226265Z","caller":"traceutil/trace.go:171","msg":"trace[213062142] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"128.332824ms","start":"2026-02-25T01:42:14.097898Z","end":"2026-02-25T01:42:14.226231Z","steps":["trace[213062142] 'process raft request' (duration: 128.083418ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:14.226451Z","caller":"traceutil/trace.go:171","msg":"trace[1730658302] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"124.402472ms","start":"2026-02-25T01:42:14.10204Z","end":"2026-02-25T01:42:14.226442Z","steps":["trace[1730658302] 'process raft request' (duration: 124.124586ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:14.363676Z","caller":"traceutil/trace.go:171","msg":"trace[67000375] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"125.107198ms","start":"2026-02-25T01:42:14.238539Z","end":"2026-02-25T01:42:14.363646Z","steps":["trace[67000375] 'process raft request' (duration: 124.445123ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:15.328211Z","caller":"traceutil/trace.go:171","msg":"trace[1794675035] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"201.886551ms","start":"2026-02-25T01:42:15.126267Z","end":"2026-02-25T01:42:15.328153Z","steps":["trace[1794675035] 'process raft request' (duration: 173.760071ms)","trace[1794675035] 'compare' (duration: 27.742751ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:15.854981Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724526911544661,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T01:42:16.035527Z","caller":"traceutil/trace.go:171","msg":"trace[814087711] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"754.565231ms","start":"2026-02-25T01:42:15.280922Z","end":"2026-02-25T01:42:16.035487Z","steps":["trace[814087711] 'process raft request' (duration: 751.007937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:16.035694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:15.280889Z","time spent":"754.721164ms","remote":"127.0.0.1:60994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T01:42:16.039572Z","caller":"traceutil/trace.go:171","msg":"trace[1758855939] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"694.563382ms","start":"2026-02-25T01:42:15.344975Z","end":"2026-02-25T01:42:16.039538Z","steps":["trace[1758855939] 'process raft request' (duration: 694.303486ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:16.039624Z","caller":"traceutil/trace.go:171","msg":"trace[263968371] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"557.980334ms","start":"2026-02-25T01:42:15.481609Z","end":"2026-02-25T01:42:16.039589Z","steps":["trace[263968371] 'process raft request' (duration: 557.863641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:16.039765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:15.344925Z","time spent":"694.741786ms","remote":"127.0.0.1:60994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:16.039829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:15.481574Z","time spent":"558.150018ms","remote":"127.0.0.1:60994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T01:42:16.040382Z","caller":"traceutil/trace.go:171","msg":"trace[1102254339] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"356.496993ms","start":"2026-02-25T01:42:15.683804Z","end":"2026-02-25T01:42:16.040301Z","steps":["trace[1102254339] 'process raft request' (duration: 355.726865ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:16.040423Z","caller":"traceutil/trace.go:171","msg":"trace[2130182815] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:518; }","duration":"686.055131ms","start":"2026-02-25T01:42:15.354319Z","end":"2026-02-25T01:42:16.040374Z","steps":["trace[2130182815] 'read index received' (duration: 677.786897ms)","trace[2130182815] 'applied index is now lower than readState.Index' (duration: 8.266624ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:16.040532Z","caller":"traceutil/trace.go:171","msg":"trace[1515745195] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"157.654023ms","start":"2026-02-25T01:42:15.882864Z","end":"2026-02-25T01:42:16.040518Z","steps":["trace[1515745195] 'process raft request' (duration: 157.087999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:16.04054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:15.683772Z","time spent":"356.700507ms","remote":"127.0.0.1:60994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T01:42:16.040791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.432241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-25T01:42:16.040845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.002659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T01:42:16.040851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.645204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T01:42:16.040878Z","caller":"traceutil/trace.go:171","msg":"trace[1902854979] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:505; }","duration":"634.084281ms","start":"2026-02-25T01:42:15.406785Z","end":"2026-02-25T01:42:16.04087Z","steps":["trace[1902854979] 'agreement among raft nodes before linearized reading' (duration: 633.930117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:16.040911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:15.406763Z","time spent":"634.142433ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T01:42:16.040907Z","caller":"traceutil/trace.go:171","msg":"trace[1288586282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:505; }","duration":"548.741977ms","start":"2026-02-25T01:42:15.492142Z","end":"2026-02-25T01:42:16.040884Z","steps":["trace[1288586282] 'agreement among raft nodes before linearized reading' (duration: 548.587154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:16.040984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:15.492112Z","time spent":"548.86431ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T01:42:16.041083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.413886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:42:16.041113Z","caller":"traceutil/trace.go:171","msg":"trace[1318437663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:505; }","duration":"550.456348ms","start":"2026-02-25T01:42:15.490647Z","end":"2026-02-25T01:42:16.041104Z","steps":["trace[1318437663] 'agreement among raft nodes before linearized reading' (duration: 550.377756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:16.041138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.342446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:42:16.041149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:15.490636Z","time spent":"550.502728ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T01:42:16.041167Z","caller":"traceutil/trace.go:171","msg":"trace[1531676133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:505; }","duration":"473.415448ms","start":"2026-02-25T01:42:15.567742Z","end":"2026-02-25T01:42:16.041158Z","steps":["trace[1531676133] 'agreement among raft nodes before linearized reading' (duration: 473.368757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:16.041222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.944864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T01:42:16.0413Z","caller":"traceutil/trace.go:171","msg":"trace[713099896] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:505; }","duration":"125.038507ms","start":"2026-02-25T01:42:15.916243Z","end":"2026-02-25T01:42:16.041281Z","steps":["trace[713099896] 'agreement among raft nodes before linearized reading' (duration: 124.77137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:16.041253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:15.567716Z","time spent":"473.529961ms","remote":"127.0.0.1:60940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:42:16.041831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.693578ms","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-25T01:42:16.041908Z","caller":"traceutil/trace.go:171","msg":"trace[1190795717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:505; }","duration":"563.827591ms","start":"2026-02-25T01:42:15.478065Z","end":"2026-02-25T01:42:16.041893Z","steps":["trace[1190795717] 'agreement among raft nodes before linearized reading' (duration: 563.656987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:16.041946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:15.478039Z","time spent":"563.900263ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T01:42:16.040947Z","caller":"traceutil/trace.go:171","msg":"trace[1615466398] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:505; }","duration":"686.653876ms","start":"2026-02-25T01:42:15.354248Z","end":"2026-02-25T01:42:16.040902Z","steps":["trace[1615466398] 'agreement among raft nodes before linearized reading' (duration: 686.348379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:16.044767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:15.354219Z","time spent":"690.384764ms","remote":"127.0.0.1:60974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"info","ts":"2026-02-25T01:42:23.72585Z","caller":"traceutil/trace.go:171","msg":"trace[1423462608] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:566; }","duration":"158.76346ms","start":"2026-02-25T01:42:23.567048Z","end":"2026-02-25T01:42:23.725811Z","steps":["trace[1423462608] 'read index received' (duration: 158.322809ms)","trace[1423462608] 'applied index is now lower than readState.Index' (duration: 439.231µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:23.72602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.947004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:23.726055Z","caller":"traceutil/trace.go:171","msg":"trace[1854593129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"159.082627ms","start":"2026-02-25T01:42:23.56696Z","end":"2026-02-25T01:42:23.726043Z","steps":["trace[1854593129] 'agreement among raft nodes before linearized reading' (duration: 158.985225ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:23.726241Z","caller":"traceutil/trace.go:171","msg":"trace[1801448066] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"675.925879ms","start":"2026-02-25T01:42:23.050279Z","end":"2026-02-25T01:42:23.726205Z","steps":["trace[1801448066] 'process raft request' (duration: 675.264874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:23.726404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:23.05026Z","time spent":"676.061553ms","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:24.207511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.086977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T01:42:24.207571Z","caller":"traceutil/trace.go:171","msg":"trace[1216081867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:549; }","duration":"110.166608ms","start":"2026-02-25T01:42:24.097391Z","end":"2026-02-25T01:42:24.207558Z","steps":["trace[1216081867] 'agreement among raft nodes before linearized reading' (duration: 110.021375ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:24.207492Z","caller":"traceutil/trace.go:171","msg":"trace[1475561913] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"112.965464ms","start":"2026-02-25T01:42:24.094494Z","end":"2026-02-25T01:42:24.207459Z","steps":["trace[1475561913] 'process raft request' (duration: 112.837971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:24.207677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.460911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T01:42:24.207777Z","caller":"traceutil/trace.go:171","msg":"trace[253391983] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:549; }","duration":"109.572444ms","start":"2026-02-25T01:42:24.098174Z","end":"2026-02-25T01:42:24.207746Z","steps":["trace[253391983] 'agreement among raft nodes before linearized reading' (duration: 109.368859ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:24.738225Z","caller":"traceutil/trace.go:171","msg":"trace[521317140] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"557.346103ms","start":"2026-02-25T01:42:24.180853Z","end":"2026-02-25T01:42:24.738199Z","steps":["trace[521317140] 'process raft request' (duration: 522.520765ms)","trace[521317140] 'compare' (duration: 34.677685ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:24.738363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:24.180828Z","time spent":"557.461146ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:24.821154Z","caller":"traceutil/trace.go:171","msg":"trace[1627154152] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"609.373675ms","start":"2026-02-25T01:42:24.211749Z","end":"2026-02-25T01:42:24.821123Z","steps":["trace[1627154152] 'process raft request' (duration: 609.205211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:24.821358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:24.211727Z","time spent":"609.518819ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:24.821484Z","caller":"traceutil/trace.go:171","msg":"trace[242076605] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"294.1367ms","start":"2026-02-25T01:42:24.527319Z","end":"2026-02-25T01:42:24.821456Z","steps":["trace[242076605] 'process raft request' (duration: 294.030308ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:24.821499Z","caller":"traceutil/trace.go:171","msg":"trace[159863199] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"609.457857ms","start":"2026-02-25T01:42:24.212006Z","end":"2026-02-25T01:42:24.821463Z","steps":["trace[159863199] 'process raft request' (duration: 609.061338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:24.821669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:24.21198Z","time spent":"609.614361ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:25.815304Z","caller":"traceutil/trace.go:171","msg":"trace[2066141577] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"249.962791ms","start":"2026-02-25T01:42:25.565314Z","end":"2026-02-25T01:42:25.815277Z","steps":["trace[2066141577] 'read index received' (duration: 249.774867ms)","trace[2066141577] 'applied index is now lower than readState.Index' (duration: 186.514µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:25.815342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:25.355978Z","time spent":"459.356511ms","remote":"127.0.0.1:60974","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T01:42:25.815454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.112165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:25.815522Z","caller":"traceutil/trace.go:171","msg":"trace[910796138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"250.234967ms","start":"2026-02-25T01:42:25.565261Z","end":"2026-02-25T01:42:25.815496Z","steps":["trace[910796138] 'agreement among raft nodes before linearized reading' (duration: 250.108984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:26.317435Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724526911544805,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:42:26.855388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.968668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:42:26.855516Z","caller":"traceutil/trace.go:171","msg":"trace[871963328] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:574; }","duration":"1.039054569s","start":"2026-02-25T01:42:25.816441Z","end":"2026-02-25T01:42:26.855496Z","steps":["trace[871963328] 'read index received' (duration: 749.657981ms)","trace[871963328] 'applied index is now lower than readState.Index' (duration: 289.394928ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:26.855619Z","caller":"traceutil/trace.go:171","msg":"trace[1339899319] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.039247684s","start":"2026-02-25T01:42:25.816357Z","end":"2026-02-25T01:42:26.855605Z","steps":["trace[1339899319] 'process raft request' (duration: 749.741753ms)","trace[1339899319] 'compare' (duration: 288.818975ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:26.855714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:25.816333Z","time spent":"1.039329626s","remote":"127.0.0.1:60974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:26.855932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.755961ms","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-25T01:42:26.85596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.871225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T01:42:26.856034Z","caller":"traceutil/trace.go:171","msg":"trace[1450354824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:554; }","duration":"113.997908ms","start":"2026-02-25T01:42:26.742018Z","end":"2026-02-25T01:42:26.856016Z","steps":["trace[1450354824] 'agreement among raft nodes before linearized reading' (duration: 113.769442ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:26.856127Z","caller":"traceutil/trace.go:171","msg":"trace[1304049018] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:554; }","duration":"674.895525ms","start":"2026-02-25T01:42:26.181096Z","end":"2026-02-25T01:42:26.855992Z","steps":["trace[1304049018] 'agreement among raft nodes before linearized reading' (duration: 674.66149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:26.856182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:26.181052Z","time spent":"675.11747ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T01:42:26.855932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03947005s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:26.856285Z","caller":"traceutil/trace.go:171","msg":"trace[198607734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"1.039839609s","start":"2026-02-25T01:42:25.816435Z","end":"2026-02-25T01:42:26.856274Z","steps":["trace[198607734] 'agreement among raft nodes before linearized reading' (duration: 1.039438169s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:26.856318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:25.816388Z","time spent":"1.039922881s","remote":"127.0.0.1:60924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:42:26.856498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.619463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T01:42:26.856797Z","caller":"traceutil/trace.go:171","msg":"trace[1848038205] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:554; }","duration":"645.181627ms","start":"2026-02-25T01:42:26.211589Z","end":"2026-02-25T01:42:26.85677Z","steps":["trace[1848038205] 'agreement among raft nodes before linearized reading' (duration: 644.279046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:26.856898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:26.211561Z","time spent":"645.32141ms","remote":"127.0.0.1:32980","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-25T01:42:31.699056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.984341ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T01:42:42.521377Z","caller":"traceutil/trace.go:171","msg":"trace[83572515] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"338.43432ms","start":"2026-02-25T01:42:42.182921Z","end":"2026-02-25T01:42:42.521356Z","steps":["trace[83572515] 'process raft request' (duration: 338.300097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:42.521517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:42.18291Z","time spent":"338.541083ms","remote":"127.0.0.1:32804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T01:42:43.224769Z","caller":"traceutil/trace.go:171","msg":"trace[1209717505] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"214.207522ms","start":"2026-02-25T01:42:43.010549Z","end":"2026-02-25T01:42:43.224757Z","steps":["trace[1209717505] 'process raft request' (duration: 214.020818ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:43.224764Z","caller":"traceutil/trace.go:171","msg":"trace[567543859] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"217.472219ms","start":"2026-02-25T01:42:43.007256Z","end":"2026-02-25T01:42:43.224728Z","steps":["trace[567543859] 'process raft request' (duration: 170.546977ms)","trace[567543859] 'compare' (duration: 46.359849ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:43.224816Z","caller":"traceutil/trace.go:171","msg":"trace[553128077] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"214.234053ms","start":"2026-02-25T01:42:43.010563Z","end":"2026-02-25T01:42:43.224797Z","steps":["trace[553128077] 'process raft request' (duration: 214.073989ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:43.224742Z","caller":"traceutil/trace.go:171","msg":"trace[945001615] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"214.064339ms","start":"2026-02-25T01:42:43.010656Z","end":"2026-02-25T01:42:43.224721Z","steps":["trace[945001615] 'process raft request' (duration: 214.024868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:43.642491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.454325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:42:43.64255Z","caller":"traceutil/trace.go:171","msg":"trace[460411985] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:686; }","duration":"179.561067ms","start":"2026-02-25T01:42:43.462976Z","end":"2026-02-25T01:42:43.642537Z","steps":["trace[460411985] 'count revisions from in-memory index tree' (duration: 179.387253ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:47.581691Z","caller":"traceutil/trace.go:171","msg":"trace[1079509516] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"307.664367ms","start":"2026-02-25T01:42:47.274006Z","end":"2026-02-25T01:42:47.58167Z","steps":["trace[1079509516] 'process raft request' (duration: 307.473262ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:47.5818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:47.273989Z","time spent":"307.75429ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:49.004321Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724526911545085,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T01:42:49.075535Z","caller":"traceutil/trace.go:171","msg":"trace[800166231] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"727.987363ms","start":"2026-02-25T01:42:48.347514Z","end":"2026-02-25T01:42:49.075502Z","steps":["trace[800166231] 'process raft request' (duration: 727.828509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:49.075728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:48.34749Z","time spent":"728.134096ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:49.223791Z","caller":"traceutil/trace.go:171","msg":"trace[1154142130] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:725; }","duration":"720.068986ms","start":"2026-02-25T01:42:48.503697Z","end":"2026-02-25T01:42:49.223766Z","steps":["trace[1154142130] 'read index received' (duration: 571.64961ms)","trace[1154142130] 'applied index is now lower than readState.Index' (duration: 148.418026ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:49.224061Z","caller":"traceutil/trace.go:171","msg":"trace[1559517567] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"858.655751ms","start":"2026-02-25T01:42:48.365393Z","end":"2026-02-25T01:42:49.224049Z","steps":["trace[1559517567] 'process raft request' (duration: 857.544465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:49.224156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:48.365371Z","time spent":"858.719843ms","remote":"127.0.0.1:32910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4728,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:49.224203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:48.562699Z","time spent":"661.48949ms","remote":"127.0.0.1:32804","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T01:42:49.224355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.630449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:49.224431Z","caller":"traceutil/trace.go:171","msg":"trace[1320313276] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:700; }","duration":"720.726472ms","start":"2026-02-25T01:42:48.503686Z","end":"2026-02-25T01:42:49.224413Z","steps":["trace[1320313276] 'agreement among raft nodes before linearized reading' (duration: 720.572178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:49.224485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:48.503672Z","time spent":"720.803213ms","remote":"127.0.0.1:33024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:42:49.224735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.266707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T01:42:49.224793Z","caller":"traceutil/trace.go:171","msg":"trace[550090458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:700; }","duration":"192.39098ms","start":"2026-02-25T01:42:49.032374Z","end":"2026-02-25T01:42:49.224765Z","steps":["trace[550090458] 'agreement among raft nodes before linearized reading' (duration: 192.170575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:49.422918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.638156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:42:49.423083Z","caller":"traceutil/trace.go:171","msg":"trace[739936962] linearizableReadLoop","detail":"{readStateIndex:730; appliedIndex:728; }","duration":"173.986337ms","start":"2026-02-25T01:42:49.249074Z","end":"2026-02-25T01:42:49.42306Z","steps":["trace[739936962] 'read index received' (duration: 39.330604ms)","trace[739936962] 'applied index is now lower than readState.Index' (duration: 134.653993ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:49.423124Z","caller":"traceutil/trace.go:171","msg":"trace[326039285] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"194.835227ms","start":"2026-02-25T01:42:49.22825Z","end":"2026-02-25T01:42:49.423085Z","steps":["trace[326039285] 'process raft request' (duration: 84.895924ms)","trace[326039285] 'compare' (duration: 109.437181ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:49.423337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.938083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T01:42:49.423397Z","caller":"traceutil/trace.go:171","msg":"trace[1127304625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:702; }","duration":"150.034315ms","start":"2026-02-25T01:42:49.273345Z","end":"2026-02-25T01:42:49.423379Z","steps":["trace[1127304625] 'agreement among raft nodes before linearized reading' (duration: 149.853101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:49.423421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.307185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T01:42:49.423536Z","caller":"traceutil/trace.go:171","msg":"trace[82612243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:702; }","duration":"174.472979ms","start":"2026-02-25T01:42:49.249035Z","end":"2026-02-25T01:42:49.423508Z","steps":["trace[82612243] 'agreement among raft nodes before linearized reading' (duration: 174.138621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:49.423827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.014384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T01:42:49.423972Z","caller":"traceutil/trace.go:171","msg":"trace[822579575] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:702; }","duration":"150.193428ms","start":"2026-02-25T01:42:49.273762Z","end":"2026-02-25T01:42:49.423956Z","steps":["trace[822579575] 'agreement among raft nodes before linearized reading' (duration: 149.554173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:49.700103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.058668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:42:49.700415Z","caller":"traceutil/trace.go:171","msg":"trace[231766208] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"271.869967ms","start":"2026-02-25T01:42:49.428498Z","end":"2026-02-25T01:42:49.700368Z","steps":["trace[231766208] 'process raft request' (duration: 109.37736ms)","trace[231766208] 'compare' (duration: 161.915273ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:49.700503Z","caller":"traceutil/trace.go:171","msg":"trace[2899496] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:730; }","duration":"133.384634ms","start":"2026-02-25T01:42:49.567096Z","end":"2026-02-25T01:42:49.70048Z","steps":["trace[2899496] 'read index received' (duration: 27.064886ms)","trace[2899496] 'applied index is now lower than readState.Index' (duration: 106.318318ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:49.700536Z","caller":"traceutil/trace.go:171","msg":"trace[322244987] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"271.797555ms","start":"2026-02-25T01:42:49.428714Z","end":"2026-02-25T01:42:49.700511Z","steps":["trace[322244987] 'process raft request' (duration: 271.5683ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:49.700569Z","caller":"traceutil/trace.go:171","msg":"trace[1928447999] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"270.622178ms","start":"2026-02-25T01:42:49.429912Z","end":"2026-02-25T01:42:49.700534Z","steps":["trace[1928447999] 'process raft request' (duration: 270.520395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:49.700658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.549157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:49.700702Z","caller":"traceutil/trace.go:171","msg":"trace[772856904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:705; }","duration":"133.65524ms","start":"2026-02-25T01:42:49.567034Z","end":"2026-02-25T01:42:49.700689Z","steps":["trace[772856904] 'agreement among raft nodes before linearized reading' (duration: 133.514836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:49.700841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.719325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T01:42:49.700898Z","caller":"traceutil/trace.go:171","msg":"trace[1276775085] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:705; }","duration":"114.794997ms","start":"2026-02-25T01:42:49.586089Z","end":"2026-02-25T01:42:49.700884Z","steps":["trace[1276775085] 'agreement among raft nodes before linearized reading' (duration: 114.569962ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:07.883432Z","caller":"traceutil/trace.go:171","msg":"trace[1947011611] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"380.285054ms","start":"2026-02-25T01:43:07.503129Z","end":"2026-02-25T01:43:07.883414Z","steps":["trace[1947011611] 'process raft request' (duration: 380.1294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:07.883569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:07.503111Z","time spent":"380.381046ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:43:08.026375Z","caller":"traceutil/trace.go:171","msg":"trace[783965736] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"120.062571ms","start":"2026-02-25T01:43:07.906285Z","end":"2026-02-25T01:43:08.026348Z","steps":["trace[783965736] 'process raft request' (duration: 80.039931ms)","trace[783965736] 'compare' (duration: 39.446987ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:43:08.048716Z","caller":"traceutil/trace.go:171","msg":"trace[444459976] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"142.250242ms","start":"2026-02-25T01:43:07.906439Z","end":"2026-02-25T01:43:08.048689Z","steps":["trace[444459976] 'process raft request' (duration: 141.966605ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:08.049042Z","caller":"traceutil/trace.go:171","msg":"trace[1416157886] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"142.532269ms","start":"2026-02-25T01:43:07.906494Z","end":"2026-02-25T01:43:08.049027Z","steps":["trace[1416157886] 'process raft request' (duration: 142.14183ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:08.04927Z","caller":"traceutil/trace.go:171","msg":"trace[828838071] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"142.377715ms","start":"2026-02-25T01:43:07.906864Z","end":"2026-02-25T01:43:08.049241Z","steps":["trace[828838071] 'process raft request' (duration: 141.992916ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:09.766525Z","caller":"traceutil/trace.go:171","msg":"trace[1574918582] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:794; }","duration":"137.662694ms","start":"2026-02-25T01:43:09.628834Z","end":"2026-02-25T01:43:09.766497Z","steps":["trace[1574918582] 'read index received' (duration: 137.462769ms)","trace[1574918582] 'applied index is now lower than readState.Index' (duration: 198.795µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:43:09.766653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.806037ms","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-25T01:43:09.766708Z","caller":"traceutil/trace.go:171","msg":"trace[1379416652] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:762; }","duration":"137.92205ms","start":"2026-02-25T01:43:09.628779Z","end":"2026-02-25T01:43:09.766701Z","steps":["trace[1379416652] 'agreement among raft nodes before linearized reading' (duration: 137.800057ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:09.766558Z","caller":"traceutil/trace.go:171","msg":"trace[1999748327] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"267.939825ms","start":"2026-02-25T01:43:09.49859Z","end":"2026-02-25T01:43:09.76653Z","steps":["trace[1999748327] 'process raft request' (duration: 267.73518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:10.323223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.115745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T01:43:10.323244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.537368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:43:10.323317Z","caller":"traceutil/trace.go:171","msg":"trace[1945614225] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:762; }","duration":"270.247109ms","start":"2026-02-25T01:43:10.053041Z","end":"2026-02-25T01:43:10.323288Z","steps":["trace[1945614225] 'range keys from in-memory index tree' (duration: 269.949891ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:10.323364Z","caller":"traceutil/trace.go:171","msg":"trace[982479679] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:762; }","duration":"265.713732ms","start":"2026-02-25T01:43:10.057625Z","end":"2026-02-25T01:43:10.323338Z","steps":["trace[982479679] 'range keys from in-memory index tree' (duration: 265.341323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:10.323599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.854406ms","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-25T01:43:10.323636Z","caller":"traceutil/trace.go:171","msg":"trace[653362227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:762; }","duration":"268.945119ms","start":"2026-02-25T01:43:10.054682Z","end":"2026-02-25T01:43:10.323627Z","steps":["trace[653362227] 'range keys from in-memory index tree' (duration: 268.750634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:10.323664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.907901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:43:10.323735Z","caller":"traceutil/trace.go:171","msg":"trace[1596808680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:762; }","duration":"416.025674ms","start":"2026-02-25T01:43:09.907691Z","end":"2026-02-25T01:43:10.323717Z","steps":["trace[1596808680] 'range keys from in-memory index tree' (duration: 415.750207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:10.323771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:09.907668Z","time spent":"416.095145ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T01:43:10.323772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.386326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T01:43:10.323805Z","caller":"traceutil/trace.go:171","msg":"trace[1298891491] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:762; }","duration":"291.451228ms","start":"2026-02-25T01:43:10.032344Z","end":"2026-02-25T01:43:10.323796Z","steps":["trace[1298891491] 'range keys from in-memory index tree' (duration: 291.275393ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:10.82638Z","caller":"traceutil/trace.go:171","msg":"trace[1389817475] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"497.917387ms","start":"2026-02-25T01:43:10.328441Z","end":"2026-02-25T01:43:10.826358Z","steps":["trace[1389817475] 'process raft request' (duration: 497.541048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:10.82671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:10.328416Z","time spent":"498.04709ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:43:11.062769Z","caller":"traceutil/trace.go:171","msg":"trace[1374241724] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"732.865257ms","start":"2026-02-25T01:43:10.329876Z","end":"2026-02-25T01:43:11.062742Z","steps":["trace[1374241724] 'process raft request' (duration: 732.791185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:11.063029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:10.329871Z","time spent":"733.045631ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:43:11.063131Z","caller":"traceutil/trace.go:171","msg":"trace[224631217] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"734.590697ms","start":"2026-02-25T01:43:10.328486Z","end":"2026-02-25T01:43:11.063077Z","steps":["trace[224631217] 'process raft request' (duration: 733.976022ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:11.063255Z","caller":"traceutil/trace.go:171","msg":"trace[1368972674] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"733.466611ms","start":"2026-02-25T01:43:10.329777Z","end":"2026-02-25T01:43:11.063243Z","steps":["trace[1368972674] 'process raft request' (duration: 732.854986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:11.063925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:10.32847Z","time spent":"735.156601ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:43:11.063254Z","caller":"traceutil/trace.go:171","msg":"trace[1589824066] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"733.034821ms","start":"2026-02-25T01:43:10.330201Z","end":"2026-02-25T01:43:11.063236Z","steps":["trace[1589824066] 'process raft request' (duration: 732.498878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:11.064043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:10.329759Z","time spent":"734.179477ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:43:11.064196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:10.330193Z","time spent":"733.910921ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:43:11.1954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.213065ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T01:43:11.195618Z","caller":"traceutil/trace.go:171","msg":"trace[149070345] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:795; }","duration":"697.473005ms","start":"2026-02-25T01:43:10.498123Z","end":"2026-02-25T01:43:11.195596Z","steps":["trace[149070345] 'read index received' (duration: 328.045486ms)","trace[149070345] 'applied index is now lower than readState.Index' (duration: 369.425569ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:43:11.195816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.492094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:43:11.19589Z","caller":"traceutil/trace.go:171","msg":"trace[1228321668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:767; }","duration":"628.621347ms","start":"2026-02-25T01:43:10.567255Z","end":"2026-02-25T01:43:11.195876Z","steps":["trace[1228321668] 'agreement among raft nodes before linearized reading' (duration: 628.490474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:11.195816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.669519ms","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-25T01:43:11.195984Z","caller":"traceutil/trace.go:171","msg":"trace[1665282813] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:767; }","duration":"698.049478ms","start":"2026-02-25T01:43:10.497913Z","end":"2026-02-25T01:43:11.195963Z","steps":["trace[1665282813] 'agreement among raft nodes before linearized reading' (duration: 697.802703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:11.19605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:10.497891Z","time spent":"698.1452ms","remote":"127.0.0.1:33008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:43:11.196013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:10.567239Z","time spent":"628.711099ms","remote":"127.0.0.1:60940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T01:43:27.09634Z","caller":"traceutil/trace.go:171","msg":"trace[137023618] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"175.932913ms","start":"2026-02-25T01:43:26.920384Z","end":"2026-02-25T01:43:27.096317Z","steps":["trace[137023618] 'process raft request' (duration: 175.781959ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:39.502355Z","caller":"traceutil/trace.go:171","msg":"trace[798660708] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:941; }","duration":"268.403083ms","start":"2026-02-25T01:43:39.233926Z","end":"2026-02-25T01:43:39.502329Z","steps":["trace[798660708] 'read index received' (duration: 268.208374ms)","trace[798660708] 'applied index is now lower than readState.Index' (duration: 193.899µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:43:39.5024Z","caller":"traceutil/trace.go:171","msg":"trace[1890505108] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"357.838493ms","start":"2026-02-25T01:43:39.144537Z","end":"2026-02-25T01:43:39.502376Z","steps":["trace[1890505108] 'process raft request' (duration: 357.654284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:39.502537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:39.144518Z","time spent":"357.937967ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:43:39.502601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.656534ms","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-25T01:43:39.50266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.460807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T01:43:39.502711Z","caller":"traceutil/trace.go:171","msg":"trace[1865299293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:902; }","duration":"268.814172ms","start":"2026-02-25T01:43:39.23387Z","end":"2026-02-25T01:43:39.502684Z","steps":["trace[1865299293] 'agreement among raft nodes before linearized reading' (duration: 268.56367ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:39.502729Z","caller":"traceutil/trace.go:171","msg":"trace[1746367636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:902; }","duration":"264.577052ms","start":"2026-02-25T01:43:39.238134Z","end":"2026-02-25T01:43:39.502711Z","steps":["trace[1746367636] 'agreement among raft nodes before linearized reading' (duration: 264.354142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:39.502728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.303625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T01:43:39.502771Z","caller":"traceutil/trace.go:171","msg":"trace[1146312880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:902; }","duration":"261.355377ms","start":"2026-02-25T01:43:39.241404Z","end":"2026-02-25T01:43:39.50276Z","steps":["trace[1146312880] 'agreement among raft nodes before linearized reading' (duration: 261.250832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:39.502614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.370704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T01:43:39.502893Z","caller":"traceutil/trace.go:171","msg":"trace[2077285436] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:902; }","duration":"256.613705ms","start":"2026-02-25T01:43:39.246227Z","end":"2026-02-25T01:43:39.502841Z","steps":["trace[2077285436] 'agreement among raft nodes before linearized reading' (duration: 256.265569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:39.788054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.200794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:43:39.788134Z","caller":"traceutil/trace.go:171","msg":"trace[672506318] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"281.73252ms","start":"2026-02-25T01:43:39.506389Z","end":"2026-02-25T01:43:39.788121Z","steps":["trace[672506318] 'process raft request' (duration: 164.392549ms)","trace[672506318] 'compare' (duration: 117.117551ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:43:39.797859Z","caller":"traceutil/trace.go:171","msg":"trace[1619776383] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"289.605013ms","start":"2026-02-25T01:43:39.508155Z","end":"2026-02-25T01:43:39.79776Z","steps":["trace[1619776383] 'process raft request' (duration: 289.53774ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:39.79806Z","caller":"traceutil/trace.go:171","msg":"trace[692256824] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"290.482733ms","start":"2026-02-25T01:43:39.507543Z","end":"2026-02-25T01:43:39.798026Z","steps":["trace[692256824] 'process raft request' (duration: 289.889676ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:39.798082Z","caller":"traceutil/trace.go:171","msg":"trace[485578226] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"290.42967ms","start":"2026-02-25T01:43:39.507639Z","end":"2026-02-25T01:43:39.798068Z","steps":["trace[485578226] 'process raft request' (duration: 289.918527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:52.257046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.504416ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:43:52.257202Z","caller":"traceutil/trace.go:171","msg":"trace[1223411319] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"394.568419ms","start":"2026-02-25T01:43:51.862621Z","end":"2026-02-25T01:43:52.257189Z","steps":["trace[1223411319] 'process raft request' (duration: 394.504638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:52.257257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:51.862616Z","time spent":"394.61293ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:43:52.257278Z","caller":"traceutil/trace.go:171","msg":"trace[1271531611] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"394.461536ms","start":"2026-02-25T01:43:51.862777Z","end":"2026-02-25T01:43:52.257238Z","steps":["trace[1271531611] 'process raft request' (duration: 394.387825ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:52.257375Z","caller":"traceutil/trace.go:171","msg":"trace[1374974435] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"394.795004ms","start":"2026-02-25T01:43:51.862557Z","end":"2026-02-25T01:43:52.257352Z","steps":["trace[1374974435] 'process raft request' (duration: 273.9122ms)","trace[1374974435] 'compare' (duration: 120.387992ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:43:52.257415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:51.862771Z","time spent":"394.569889ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:43:52.257468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:51.862544Z","time spent":"394.878436ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:43:52.693743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.403131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:43:52.693845Z","caller":"traceutil/trace.go:171","msg":"trace[1660245902] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:941; }","duration":"250.572945ms","start":"2026-02-25T01:43:52.443251Z","end":"2026-02-25T01:43:52.693824Z","steps":["trace[1660245902] 'count revisions from in-memory index tree' (duration: 250.316648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:52.693878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.324668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:43:52.694034Z","caller":"traceutil/trace.go:171","msg":"trace[1966646359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"116.650736ms","start":"2026-02-25T01:43:52.57734Z","end":"2026-02-25T01:43:52.693991Z","steps":["trace[1966646359] 'range keys from in-memory index tree' (duration: 116.199665ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:54.516874Z","caller":"traceutil/trace.go:171","msg":"trace[1408094449] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:997; }","duration":"240.342506ms","start":"2026-02-25T01:43:54.276508Z","end":"2026-02-25T01:43:54.51685Z","steps":["trace[1408094449] 'read index received' (duration: 240.012098ms)","trace[1408094449] 'applied index is now lower than readState.Index' (duration: 329.597µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:43:54.516986Z","caller":"traceutil/trace.go:171","msg":"trace[796163263] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"419.232385ms","start":"2026-02-25T01:43:54.097743Z","end":"2026-02-25T01:43:54.516975Z","steps":["trace[796163263] 'process raft request' (duration: 418.863637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:54.517098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:54.09773Z","time spent":"419.282776ms","remote":"127.0.0.1:32910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5242,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:43:54.517215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.776026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:43:54.517262Z","caller":"traceutil/trace.go:171","msg":"trace[1072709486] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:954; }","duration":"234.876358ms","start":"2026-02-25T01:43:54.282374Z","end":"2026-02-25T01:43:54.51725Z","steps":["trace[1072709486] 'agreement among raft nodes before linearized reading' (duration: 234.760695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:54.517267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.756085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:43:54.517327Z","caller":"traceutil/trace.go:171","msg":"trace[396049520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:954; }","duration":"240.818706ms","start":"2026-02-25T01:43:54.276492Z","end":"2026-02-25T01:43:54.517311Z","steps":["trace[396049520] 'agreement among raft nodes before linearized reading' (duration: 240.619062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:54.517545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.53976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T01:43:54.517576Z","caller":"traceutil/trace.go:171","msg":"trace[139754753] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:954; }","duration":"240.590661ms","start":"2026-02-25T01:43:54.276979Z","end":"2026-02-25T01:43:54.51757Z","steps":["trace[139754753] 'agreement among raft nodes before linearized reading' (duration: 240.11133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:55.692435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.636932ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:43:55.692656Z","caller":"traceutil/trace.go:171","msg":"trace[1721974696] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"126.300791ms","start":"2026-02-25T01:43:55.566335Z","end":"2026-02-25T01:43:55.692636Z","steps":["trace[1721974696] 'read index received' (duration: 43.661µs)","trace[1721974696] 'applied index is now lower than readState.Index' (duration: 126.228149ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:43:55.692788Z","caller":"traceutil/trace.go:171","msg":"trace[1374354132] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"210.032117ms","start":"2026-02-25T01:43:55.482743Z","end":"2026-02-25T01:43:55.692775Z","steps":["trace[1374354132] 'process raft request' (duration: 69.965515ms)","trace[1374354132] 'compare' (duration: 139.371136ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:43:55.693118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.735381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:43:55.693238Z","caller":"traceutil/trace.go:171","msg":"trace[1599279186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:959; }","duration":"126.882865ms","start":"2026-02-25T01:43:55.566329Z","end":"2026-02-25T01:43:55.693212Z","steps":["trace[1599279186] 'agreement among raft nodes before linearized reading' (duration: 126.531996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:55.693768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.799639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T01:43:55.693873Z","caller":"traceutil/trace.go:171","msg":"trace[1300071909] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:959; }","duration":"110.995653ms","start":"2026-02-25T01:43:55.582854Z","end":"2026-02-25T01:43:55.69385Z","steps":["trace[1300071909] 'agreement among raft nodes before linearized reading' (duration: 110.632525ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:56.571882Z","caller":"traceutil/trace.go:171","msg":"trace[534180038] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"273.951831ms","start":"2026-02-25T01:43:56.297877Z","end":"2026-02-25T01:43:56.571829Z","steps":["trace[534180038] 'process raft request' (duration: 273.702715ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:56.707772Z","caller":"traceutil/trace.go:171","msg":"trace[184502091] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"130.953569ms","start":"2026-02-25T01:43:56.576785Z","end":"2026-02-25T01:43:56.707739Z","steps":["trace[184502091] 'process raft request' (duration: 54.342799ms)","trace[184502091] 'compare' (duration: 76.324023ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:43:56.707801Z","caller":"traceutil/trace.go:171","msg":"trace[220886543] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"130.946019ms","start":"2026-02-25T01:43:56.57683Z","end":"2026-02-25T01:43:56.707776Z","steps":["trace[220886543] 'process raft request' (duration: 130.777835ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:56.707763Z","caller":"traceutil/trace.go:171","msg":"trace[772737315] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"130.760315ms","start":"2026-02-25T01:43:56.576969Z","end":"2026-02-25T01:43:56.707729Z","steps":["trace[772737315] 'process raft request' (duration: 130.709054ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:57.426046Z","caller":"traceutil/trace.go:171","msg":"trace[713365745] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"443.256226ms","start":"2026-02-25T01:43:56.982764Z","end":"2026-02-25T01:43:57.42602Z","steps":["trace[713365745] 'process raft request' (duration: 396.608516ms)","trace[713365745] 'compare' (duration: 46.241221ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:43:57.426138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:56.982743Z","time spent":"443.352479ms","remote":"127.0.0.1:32804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":864,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T01:43:58.309934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.763641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T01:43:58.31022Z","caller":"traceutil/trace.go:171","msg":"trace[1370032569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:966; }","duration":"195.091099ms","start":"2026-02-25T01:43:58.115107Z","end":"2026-02-25T01:43:58.310198Z","steps":["trace[1370032569] 'range keys from in-memory index tree' (duration: 194.580326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:58.310437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.670716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:43:58.31053Z","caller":"traceutil/trace.go:171","msg":"trace[1362314749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:966; }","duration":"743.792278ms","start":"2026-02-25T01:43:57.566719Z","end":"2026-02-25T01:43:58.310512Z","steps":["trace[1362314749] 'range keys from in-memory index tree' (duration: 743.566063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:58.310624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:57.566688Z","time spent":"743.921681ms","remote":"127.0.0.1:60924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:43:58.309963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.671356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T01:43:58.311056Z","caller":"traceutil/trace.go:171","msg":"trace[1103609828] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:966; }","duration":"542.815342ms","start":"2026-02-25T01:43:57.768218Z","end":"2026-02-25T01:43:58.311033Z","steps":["trace[1103609828] 'range keys from in-memory index tree' (duration: 541.457521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:58.311116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:57.768198Z","time spent":"542.902165ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T01:43:58.509681Z","caller":"traceutil/trace.go:171","msg":"trace[1442735097] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"194.007203ms","start":"2026-02-25T01:43:58.31565Z","end":"2026-02-25T01:43:58.509657Z","steps":["trace[1442735097] 'process raft request' (duration: 193.858879ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:58.640287Z","caller":"traceutil/trace.go:171","msg":"trace[1715891767] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"324.837339ms","start":"2026-02-25T01:43:58.315424Z","end":"2026-02-25T01:43:58.640262Z","steps":["trace[1715891767] 'process raft request' (duration: 280.605276ms)","trace[1715891767] 'compare' (duration: 43.798653ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:43:58.640549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:58.315396Z","time spent":"324.952082ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:43:58.933339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.026851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:43:58.93352Z","caller":"traceutil/trace.go:171","msg":"trace[1550761741] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"201.828045ms","start":"2026-02-25T01:43:58.731675Z","end":"2026-02-25T01:43:58.933503Z","steps":["trace[1550761741] 'process raft request' (duration: 201.769643ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:58.933623Z","caller":"traceutil/trace.go:171","msg":"trace[856455816] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"219.321893ms","start":"2026-02-25T01:43:58.714245Z","end":"2026-02-25T01:43:58.933567Z","steps":["trace[856455816] 'process raft request' (duration: 54.994295ms)","trace[856455816] 'compare' (duration: 163.906768ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:43:59.773272Z","caller":"traceutil/trace.go:171","msg":"trace[1102666270] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"158.575175ms","start":"2026-02-25T01:43:59.614675Z","end":"2026-02-25T01:43:59.77325Z","steps":["trace[1102666270] 'process raft request' (duration: 158.415541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:44:01.199633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.779348ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T01:44:01.199733Z","caller":"traceutil/trace.go:171","msg":"trace[1025613211] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1019; }","duration":"686.0922ms","start":"2026-02-25T01:44:00.513624Z","end":"2026-02-25T01:44:01.199716Z","steps":["trace[1025613211] 'read index received' (duration: 42.021µs)","trace[1025613211] 'applied index is now lower than readState.Index' (duration: 686.047949ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:44:01.199908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.279504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T01:44:01.199943Z","caller":"traceutil/trace.go:171","msg":"trace[1658184797] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:974; }","duration":"686.322035ms","start":"2026-02-25T01:44:00.513611Z","end":"2026-02-25T01:44:01.199934Z","steps":["trace[1658184797] 'agreement among raft nodes before linearized reading' (duration: 686.182402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:44:01.200041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:44:00.513556Z","time spent":"686.47466ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T01:44:01.332983Z","caller":"traceutil/trace.go:171","msg":"trace[899503834] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"133.207392ms","start":"2026-02-25T01:44:01.199754Z","end":"2026-02-25T01:44:01.332961Z","steps":["trace[899503834] 'read index received' (duration: 133.10625ms)","trace[899503834] 'applied index is now lower than readState.Index' (duration: 100.202µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:44:01.333114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.654055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:44:01.333146Z","caller":"traceutil/trace.go:171","msg":"trace[38180120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:975; }","duration":"767.699807ms","start":"2026-02-25T01:44:00.565436Z","end":"2026-02-25T01:44:01.333136Z","steps":["trace[38180120] 'agreement among raft nodes before linearized reading' (duration: 767.611744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:44:01.333176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:44:00.565373Z","time spent":"767.796259ms","remote":"127.0.0.1:60940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T01:44:01.333156Z","caller":"traceutil/trace.go:171","msg":"trace[226962529] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"462.961287ms","start":"2026-02-25T01:44:00.870166Z","end":"2026-02-25T01:44:01.333128Z","steps":["trace[226962529] 'process raft request' (duration: 462.593119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:44:01.333307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.840651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:44:01.333348Z","caller":"traceutil/trace.go:171","msg":"trace[363330363] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:975; }","duration":"592.883232ms","start":"2026-02-25T01:44:00.740453Z","end":"2026-02-25T01:44:01.333336Z","steps":["trace[363330363] 'agreement among raft nodes before linearized reading' (duration: 592.753389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:44:01.333343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:44:00.870139Z","time spent":"463.12061ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:44:01.333378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:44:00.740412Z","time spent":"592.959774ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T01:44:01.333409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.505146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T01:44:01.333461Z","caller":"traceutil/trace.go:171","msg":"trace[2040509130] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:975; }","duration":"379.581319ms","start":"2026-02-25T01:44:00.953866Z","end":"2026-02-25T01:44:01.333447Z","steps":["trace[2040509130] 'agreement among raft nodes before linearized reading' (duration: 379.167659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:44:01.333473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.176739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T01:44:01.333508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:44:00.953856Z","time spent":"379.63297ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T01:44:01.3335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.609737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:44:01.333559Z","caller":"traceutil/trace.go:171","msg":"trace[1447659772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:975; }","duration":"688.317372ms","start":"2026-02-25T01:44:00.645222Z","end":"2026-02-25T01:44:01.333539Z","steps":["trace[1447659772] 'agreement among raft nodes before linearized reading' (duration: 688.061236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:44:01.333606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:44:00.6452Z","time spent":"688.397324ms","remote":"127.0.0.1:32980","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-25T01:44:01.333652Z","caller":"traceutil/trace.go:171","msg":"trace[866824905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:975; }","duration":"396.716079ms","start":"2026-02-25T01:44:00.936848Z","end":"2026-02-25T01:44:01.333564Z","steps":["trace[866824905] 'agreement among raft nodes before linearized reading' (duration: 396.583816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:44:01.333689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:44:00.936832Z","time spent":"396.847582ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T01:44:01.369743Z","caller":"traceutil/trace.go:171","msg":"trace[1566999357] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"165.251461ms","start":"2026-02-25T01:44:01.204473Z","end":"2026-02-25T01:44:01.369724Z","steps":["trace[1566999357] 'process raft request' (duration: 165.140779ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:44:01.521089Z","caller":"traceutil/trace.go:171","msg":"trace[849924516] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"182.837282ms","start":"2026-02-25T01:44:01.338228Z","end":"2026-02-25T01:44:01.521065Z","steps":["trace[849924516] 'process raft request' (duration: 182.571876ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:44:01.521079Z","caller":"traceutil/trace.go:171","msg":"trace[2140464411] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"182.74935ms","start":"2026-02-25T01:44:01.338306Z","end":"2026-02-25T01:44:01.521055Z","steps":["trace[2140464411] 'process raft request' (duration: 182.672418ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:44:01.521369Z","caller":"traceutil/trace.go:171","msg":"trace[1463146375] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"182.863232ms","start":"2026-02-25T01:44:01.338496Z","end":"2026-02-25T01:44:01.521359Z","steps":["trace[1463146375] 'process raft request' (duration: 182.524504ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:44:01.521358Z","caller":"traceutil/trace.go:171","msg":"trace[98622092] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"182.845032ms","start":"2026-02-25T01:44:01.338492Z","end":"2026-02-25T01:44:01.521337Z","steps":["trace[98622092] 'process raft request' (duration: 182.737709ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:44:03.684902Z","caller":"traceutil/trace.go:171","msg":"trace[1181619427] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"154.95507ms","start":"2026-02-25T01:44:03.52993Z","end":"2026-02-25T01:44:03.684885Z","steps":["trace[1181619427] 'process raft request' (duration: 154.844438ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:44:03.685261Z","caller":"traceutil/trace.go:171","msg":"trace[545196666] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"154.641633ms","start":"2026-02-25T01:44:03.530586Z","end":"2026-02-25T01:44:03.685228Z","steps":["trace[545196666] 'process raft request' (duration: 154.273815ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:44:03.685283Z","caller":"traceutil/trace.go:171","msg":"trace[466854661] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"154.236084ms","start":"2026-02-25T01:44:03.530999Z","end":"2026-02-25T01:44:03.685236Z","steps":["trace[466854661] 'process raft request' (duration: 154.08045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:44:06.078592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.862355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:44:06.07869Z","caller":"traceutil/trace.go:171","msg":"trace[746454439] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1033; }","duration":"605.913476ms","start":"2026-02-25T01:44:05.472762Z","end":"2026-02-25T01:44:06.078676Z","steps":["trace[746454439] 'read index received' (duration: 445.928379ms)","trace[746454439] 'applied index is now lower than readState.Index' (duration: 159.983557ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:44:06.078765Z","caller":"traceutil/trace.go:171","msg":"trace[11252305] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"624.624843ms","start":"2026-02-25T01:44:05.454094Z","end":"2026-02-25T01:44:06.078719Z","steps":["trace[11252305] 'process raft request' (duration: 464.550874ms)","trace[11252305] 'compare' (duration: 159.455534ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:44:06.07893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:44:05.454071Z","time spent":"624.774117ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:44:06.078952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.02197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:44:06.078986Z","caller":"traceutil/trace.go:171","msg":"trace[1414709539] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:987; }","duration":"532.102492ms","start":"2026-02-25T01:44:05.546873Z","end":"2026-02-25T01:44:06.078976Z","steps":["trace[1414709539] 'agreement among raft nodes before linearized reading' (duration: 531.959578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:44:06.078966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.34983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-25T01:44:06.07902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:44:05.546848Z","time spent":"532.163782ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T01:44:06.079037Z","caller":"traceutil/trace.go:171","msg":"trace[219214828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:987; }","duration":"390.436642ms","start":"2026-02-25T01:44:05.688582Z","end":"2026-02-25T01:44:06.079019Z","steps":["trace[219214828] 'agreement among raft nodes before linearized reading' (duration: 390.254197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:44:06.079082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:44:05.688578Z","time spent":"390.495963ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T01:44:06.078803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.051189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-02-25T01:44:06.079161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.027825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T01:44:06.079171Z","caller":"traceutil/trace.go:171","msg":"trace[879967863] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:987; }","duration":"606.455409ms","start":"2026-02-25T01:44:05.472702Z","end":"2026-02-25T01:44:06.079157Z","steps":["trace[879967863] 'agreement among raft nodes before linearized reading' (duration: 606.054849ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:44:06.079187Z","caller":"traceutil/trace.go:171","msg":"trace[954195690] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:987; }","duration":"391.088796ms","start":"2026-02-25T01:44:05.68809Z","end":"2026-02-25T01:44:06.079179Z","steps":["trace[954195690] 'agreement among raft nodes before linearized reading' (duration: 391.014895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:44:06.079209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:44:05.47268Z","time spent":"606.5175ms","remote":"127.0.0.1:32900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-25T01:44:06.079215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:44:05.688078Z","time spent":"391.131348ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T01:44:06.079336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.681157ms","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-25T01:44:06.079383Z","caller":"traceutil/trace.go:171","msg":"trace[439096288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:987; }","duration":"390.78019ms","start":"2026-02-25T01:44:05.688591Z","end":"2026-02-25T01:44:06.079371Z","steps":["trace[439096288] 'agreement among raft nodes before linearized reading' (duration: 390.651497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:44:06.079414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:44:05.688581Z","time spent":"390.826001ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T01:44:06.079452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.36256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:44:06.079494Z","caller":"traceutil/trace.go:171","msg":"trace[733819528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"512.431261ms","start":"2026-02-25T01:44:05.56705Z","end":"2026-02-25T01:44:06.079481Z","steps":["trace[733819528] 'agreement among raft nodes before linearized reading' (duration: 512.36469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:44:06.07953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:44:05.567023Z","time spent":"512.499053ms","remote":"127.0.0.1:60940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:44:06.480261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.489436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:44:06.48036Z","caller":"traceutil/trace.go:171","msg":"trace[188044821] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"395.139772ms","start":"2026-02-25T01:44:06.085201Z","end":"2026-02-25T01:44:06.48034Z","steps":["trace[188044821] 'process raft request' (duration: 259.496593ms)","trace[188044821] 'compare' (duration: 135.334372ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:44:06.480439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:44:06.085183Z","time spent":"395.217574ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:44:06.482863Z","caller":"traceutil/trace.go:171","msg":"trace[745553616] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"391.466497ms","start":"2026-02-25T01:44:06.091373Z","end":"2026-02-25T01:44:06.48284Z","steps":["trace[745553616] 'process raft request' (duration: 391.425566ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:44:06.482935Z","caller":"traceutil/trace.go:171","msg":"trace[1400637199] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"397.467256ms","start":"2026-02-25T01:44:06.085448Z","end":"2026-02-25T01:44:06.482915Z","steps":["trace[1400637199] 'process raft request' (duration: 397.19135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:44:06.483004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:44:06.091356Z","time spent":"391.577659ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:44:06.483054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:44:06.085429Z","time spent":"397.57741ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:44:06.483175Z","caller":"traceutil/trace.go:171","msg":"trace[1256884543] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"397.701442ms","start":"2026-02-25T01:44:06.085455Z","end":"2026-02-25T01:44:06.483157Z","steps":["trace[1256884543] 'process raft request' (duration: 397.309243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:44:06.483286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:44:06.085444Z","time spent":"397.788344ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:44:06.833822Z","caller":"traceutil/trace.go:171","msg":"trace[362724962] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"177.362764ms","start":"2026-02-25T01:44:06.656434Z","end":"2026-02-25T01:44:06.833797Z","steps":["trace[362724962] 'process raft request' (duration: 177.168409ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:44:35.369043Z","caller":"traceutil/trace.go:171","msg":"trace[1021589111] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"109.629161ms","start":"2026-02-25T01:44:35.25938Z","end":"2026-02-25T01:44:35.36901Z","steps":["trace[1021589111] 'process raft request' (duration: 85.632903ms)","trace[1021589111] 'compare' (duration: 23.879335ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:44:37.004805Z","caller":"traceutil/trace.go:171","msg":"trace[480512755] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"200.834831ms","start":"2026-02-25T01:44:36.803938Z","end":"2026-02-25T01:44:37.004773Z","steps":["trace[480512755] 'process raft request' (duration: 200.79447ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:44:37.004872Z","caller":"traceutil/trace.go:171","msg":"trace[1691861801] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"200.939224ms","start":"2026-02-25T01:44:36.803902Z","end":"2026-02-25T01:44:37.004842Z","steps":["trace[1691861801] 'process raft request' (duration: 161.237188ms)","trace[1691861801] 'compare' (duration: 39.46148ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:44:37.509397Z","caller":"traceutil/trace.go:171","msg":"trace[186708622] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"131.137721ms","start":"2026-02-25T01:44:37.377986Z","end":"2026-02-25T01:44:37.509124Z","steps":["trace[186708622] 'process raft request' (duration: 130.976168ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:44:37.691384Z","caller":"traceutil/trace.go:171","msg":"trace[1413609905] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"174.412289ms","start":"2026-02-25T01:44:37.516929Z","end":"2026-02-25T01:44:37.691341Z","steps":["trace[1413609905] 'process raft request' (duration: 105.489427ms)","trace[1413609905] 'compare' (duration: 68.641536ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:44:37.691426Z","caller":"traceutil/trace.go:171","msg":"trace[815608274] linearizableReadLoop","detail":"{readStateIndex:1215; appliedIndex:1214; }","duration":"125.723258ms","start":"2026-02-25T01:44:37.565683Z","end":"2026-02-25T01:44:37.691406Z","steps":["trace[815608274] 'read index received' (duration: 56.746014ms)","trace[815608274] 'applied index is now lower than readState.Index' (duration: 68.976374ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:44:37.691512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.821351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:44:37.691532Z","caller":"traceutil/trace.go:171","msg":"trace[1043939125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1163; }","duration":"125.889043ms","start":"2026-02-25T01:44:37.565636Z","end":"2026-02-25T01:44:37.691525Z","steps":["trace[1043939125] 'agreement among raft nodes before linearized reading' (duration: 125.840942ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:44:45.226825Z","caller":"traceutil/trace.go:171","msg":"trace[631961063] linearizableReadLoop","detail":"{readStateIndex:1249; appliedIndex:1246; }","duration":"196.580154ms","start":"2026-02-25T01:44:45.030227Z","end":"2026-02-25T01:44:45.226807Z","steps":["trace[631961063] 'read index received' (duration: 122.372231ms)","trace[631961063] 'applied index is now lower than readState.Index' (duration: 74.207033ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:44:45.226881Z","caller":"traceutil/trace.go:171","msg":"trace[2007878084] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"277.990621ms","start":"2026-02-25T01:44:44.948873Z","end":"2026-02-25T01:44:45.226863Z","steps":["trace[2007878084] 'process raft request' (duration: 203.786359ms)","trace[2007878084] 'compare' (duration: 73.988187ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:44:45.226921Z","caller":"traceutil/trace.go:171","msg":"trace[47948270] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"277.529031ms","start":"2026-02-25T01:44:44.949383Z","end":"2026-02-25T01:44:45.226912Z","steps":["trace[47948270] 'process raft request' (duration: 277.371088ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:44:45.226987Z","caller":"traceutil/trace.go:171","msg":"trace[1869301221] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"275.524805ms","start":"2026-02-25T01:44:44.951456Z","end":"2026-02-25T01:44:45.226981Z","steps":["trace[1869301221] 'process raft request' (duration: 275.32878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:44:45.22715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.911512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:44:45.227182Z","caller":"traceutil/trace.go:171","msg":"trace[107477170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1195; }","duration":"196.997003ms","start":"2026-02-25T01:44:45.030178Z","end":"2026-02-25T01:44:45.227175Z","steps":["trace[107477170] 'agreement among raft nodes before linearized reading' (duration: 196.85428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:44:45.227183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.498017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T01:44:45.227224Z","caller":"traceutil/trace.go:171","msg":"trace[116098913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1195; }","duration":"130.566888ms","start":"2026-02-25T01:44:45.096645Z","end":"2026-02-25T01:44:45.227212Z","steps":["trace[116098913] 'agreement among raft nodes before linearized reading' (duration: 130.441375ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:44:47.439841Z","caller":"traceutil/trace.go:171","msg":"trace[1852343872] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"148.476317ms","start":"2026-02-25T01:44:47.291318Z","end":"2026-02-25T01:44:47.439794Z","steps":["trace[1852343872] 'process raft request' (duration: 119.899475ms)","trace[1852343872] 'compare' (duration: 28.392138ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:44:56.301092Z","caller":"traceutil/trace.go:171","msg":"trace[836171160] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"103.646125ms","start":"2026-02-25T01:44:56.197412Z","end":"2026-02-25T01:44:56.301058Z","steps":["trace[836171160] 'process raft request' (duration: 103.42541ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:44:56.43179Z","caller":"traceutil/trace.go:171","msg":"trace[1863963366] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"121.86895ms","start":"2026-02-25T01:44:56.30989Z","end":"2026-02-25T01:44:56.431759Z","steps":["trace[1863963366] 'process raft request' (duration: 120.498399ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:45:55.23395Z","caller":"traceutil/trace.go:171","msg":"trace[1866983948] transaction","detail":"{read_only:false; response_revision:1513; number_of_response:1; }","duration":"131.867017ms","start":"2026-02-25T01:45:55.10205Z","end":"2026-02-25T01:45:55.233917Z","steps":["trace[1866983948] 'process raft request' (duration: 131.694294ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:46:03.893858Z","caller":"traceutil/trace.go:171","msg":"trace[1682560517] transaction","detail":"{read_only:false; response_revision:1553; number_of_response:1; }","duration":"100.003055ms","start":"2026-02-25T01:46:03.793833Z","end":"2026-02-25T01:46:03.893836Z","steps":["trace[1682560517] 'process raft request' (duration: 99.581017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:46:30.204495Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724526911547533,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:46:30.705289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724526911547533,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:46:31.660293Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724526911547533,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:46:31.679828Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.975655012s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T01:46:31.680248Z","caller":"traceutil/trace.go:171","msg":"trace[971585645] transaction","detail":"{read_only:false; response_revision:1667; number_of_response:1; }","duration":"1.976157453s","start":"2026-02-25T01:46:29.704062Z","end":"2026-02-25T01:46:31.68022Z","steps":["trace[971585645] 'process raft request' (duration: 1.976007169s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:46:31.680408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:46:29.704034Z","time spent":"1.976288916s","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:46:31.704607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00044628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T01:46:31.704836Z","caller":"traceutil/trace.go:171","msg":"trace[202452976] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000701515s","start":"2026-02-25T01:46:29.704113Z","end":"2026-02-25T01:46:31.704814Z","steps":["trace[202452976] 'agreement among raft nodes before linearized reading' (duration: 2.00047017s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:46:31.704908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:46:29.704099Z","time spent":"2.000778657s","remote":"127.0.0.1:60940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 01:46:31 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-25T01:46:31.73705Z","caller":"traceutil/trace.go:171","msg":"trace[125383035] transaction","detail":"{read_only:false; response_revision:1668; number_of_response:1; }","duration":"1.765598578s","start":"2026-02-25T01:46:29.971423Z","end":"2026-02-25T01:46:31.737022Z","steps":["trace[125383035] 'process raft request' (duration: 1.765056976s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:46:31.737183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:46:29.971414Z","time spent":"1.765703301s","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:46:31.75228Z","caller":"traceutil/trace.go:171","msg":"trace[582799918] linearizableReadLoop","detail":"{readStateIndex:1744; appliedIndex:1741; }","duration":"2.048101011s","start":"2026-02-25T01:46:29.704142Z","end":"2026-02-25T01:46:31.752243Z","steps":["trace[582799918] 'read index received' (duration: 1.975890766s)","trace[582799918] 'applied index is now lower than readState.Index' (duration: 72.208795ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:46:31.752776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.664788028s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T01:46:31.7529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197437559s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-02-25T01:46:31.752885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.809001973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-02-25T01:46:31.752963Z","caller":"traceutil/trace.go:171","msg":"trace[913035351] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1668; }","duration":"1.197532021s","start":"2026-02-25T01:46:30.555406Z","end":"2026-02-25T01:46:31.752938Z","steps":["trace[913035351] 'agreement among raft nodes before linearized reading' (duration: 1.197432789s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:46:31.753017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:46:30.555386Z","time spent":"1.197624404s","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-25T01:46:31.753005Z","caller":"traceutil/trace.go:171","msg":"trace[1881119014] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1668; }","duration":"1.809157226s","start":"2026-02-25T01:46:29.94381Z","end":"2026-02-25T01:46:31.752967Z","steps":["trace[1881119014] 'agreement among raft nodes before linearized reading' (duration: 1.808851679s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:46:31.753005Z","caller":"traceutil/trace.go:171","msg":"trace[1721825082] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1668; }","duration":"1.664936361s","start":"2026-02-25T01:46:30.087914Z","end":"2026-02-25T01:46:31.75285Z","steps":["trace[1721825082] 'agreement among raft nodes before linearized reading' (duration: 1.664717786s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:46:31.753145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.536814016s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T01:46:31.753203Z","caller":"traceutil/trace.go:171","msg":"trace[983342324] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1668; }","duration":"1.536901738s","start":"2026-02-25T01:46:30.216283Z","end":"2026-02-25T01:46:31.753185Z","steps":["trace[983342324] 'agreement among raft nodes before linearized reading' (duration: 1.536797625s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:46:31.753231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:46:30.21627Z","time spent":"1.536954779s","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-25T01:46:31.753205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.41350656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-25T01:46:31.753317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:46:30.087897Z","time spent":"1.665259039s","remote":"127.0.0.1:32826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":16,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-02-25T01:46:31.753377Z","caller":"traceutil/trace.go:171","msg":"trace[56553945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1668; }","duration":"1.413752656s","start":"2026-02-25T01:46:30.339613Z","end":"2026-02-25T01:46:31.753365Z","steps":["trace[56553945] 'agreement among raft nodes before linearized reading' (duration: 1.413295576s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:46:31.752909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196287983s","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-25T01:46:31.753529Z","caller":"traceutil/trace.go:171","msg":"trace[1192125113] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1668; }","duration":"1.196952328s","start":"2026-02-25T01:46:30.55656Z","end":"2026-02-25T01:46:31.753513Z","steps":["trace[1192125113] 'agreement among raft nodes before linearized reading' (duration: 1.19614222s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:46:31.753121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:46:29.943797Z","time spent":"1.80930593s","remote":"127.0.0.1:32826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":645,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-25T01:46:31.753557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:46:30.556548Z","time spent":"1.197001999s","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":560,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-25T01:46:31.753603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:46:30.339587Z","time spent":"1.41391905s","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T01:46:31.753401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204568822s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"warn","ts":"2026-02-25T01:46:31.753327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.188718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:46:31.753741Z","caller":"traceutil/trace.go:171","msg":"trace[197401735] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1668; }","duration":"766.639158ms","start":"2026-02-25T01:46:30.987092Z","end":"2026-02-25T01:46:31.753731Z","steps":["trace[197401735] 'agreement among raft nodes before linearized reading' (duration: 766.216039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:46:31.753788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:46:30.987052Z","time spent":"766.723599ms","remote":"127.0.0.1:32874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-02-25T01:46:31.753775Z","caller":"traceutil/trace.go:171","msg":"trace[723899729] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1668; }","duration":"1.204939961s","start":"2026-02-25T01:46:30.548776Z","end":"2026-02-25T01:46:31.753716Z","steps":["trace[723899729] 'agreement among raft nodes before linearized reading' (duration: 1.204406369s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:46:31.753889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:46:30.548727Z","time spent":"1.205148306s","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-25T01:46:31.752782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37288455s","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-25T01:46:31.754013Z","caller":"traceutil/trace.go:171","msg":"trace[1824497383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1668; }","duration":"1.374147819s","start":"2026-02-25T01:46:30.379814Z","end":"2026-02-25T01:46:31.753961Z","steps":["trace[1824497383] 'agreement among raft nodes before linearized reading' (duration: 1.372719446s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:46:31.754058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.988035325s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-25T01:46:31.754093Z","caller":"traceutil/trace.go:171","msg":"trace[84780031] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1668; }","duration":"1.988229099s","start":"2026-02-25T01:46:29.765854Z","end":"2026-02-25T01:46:31.754083Z","steps":["trace[84780031] 'agreement among raft nodes before linearized reading' (duration: 1.987643886s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:46:31.754123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:46:29.765834Z","time spent":"1.988282871s","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T01:46:31.754221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.588584782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T01:46:31.754274Z","caller":"traceutil/trace.go:171","msg":"trace[1028952563] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1668; }","duration":"1.588677294s","start":"2026-02-25T01:46:30.165584Z","end":"2026-02-25T01:46:31.754261Z","steps":["trace[1028952563] 'agreement among raft nodes before linearized reading' (duration: 1.586985886s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:46:31.754319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:46:30.165564Z","time spent":"1.588741906s","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T01:46:31.75406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:46:30.379793Z","time spent":"1.374254191s","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T01:46:36.066696Z","caller":"traceutil/trace.go:171","msg":"trace[506887177] transaction","detail":"{read_only:false; response_revision:1691; number_of_response:1; }","duration":"110.703467ms","start":"2026-02-25T01:46:35.955973Z","end":"2026-02-25T01:46:36.066676Z","steps":["trace[506887177] 'process raft request' (duration: 110.599245ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:46:36.101048Z","caller":"traceutil/trace.go:171","msg":"trace[735176866] transaction","detail":"{read_only:false; response_revision:1692; number_of_response:1; }","duration":"144.632144ms","start":"2026-02-25T01:46:35.956382Z","end":"2026-02-25T01:46:36.101014Z","steps":["trace[735176866] 'process raft request' (duration: 144.44808ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:46:36.101546Z","caller":"traceutil/trace.go:171","msg":"trace[1184712811] transaction","detail":"{read_only:false; response_revision:1693; number_of_response:1; }","duration":"145.134356ms","start":"2026-02-25T01:46:35.956394Z","end":"2026-02-25T01:46:36.101528Z","steps":["trace[1184712811] 'process raft request' (duration: 144.569233ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:46:36.288581Z","caller":"traceutil/trace.go:171","msg":"trace[761046636] transaction","detail":"{read_only:false; response_revision:1694; number_of_response:1; }","duration":"180.883144ms","start":"2026-02-25T01:46:36.107641Z","end":"2026-02-25T01:46:36.288524Z","steps":["trace[761046636] 'process raft request' (duration: 144.769167ms)","trace[761046636] 'compare' (duration: 35.899012ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:46:36.928542Z","caller":"traceutil/trace.go:171","msg":"trace[967718193] transaction","detail":"{read_only:false; response_revision:1699; number_of_response:1; }","duration":"140.983931ms","start":"2026-02-25T01:46:36.787529Z","end":"2026-02-25T01:46:36.928513Z","steps":["trace[967718193] 'process raft request' (duration: 140.892919ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:46:36.929016Z","caller":"traceutil/trace.go:171","msg":"trace[1547259214] transaction","detail":"{read_only:false; response_revision:1698; number_of_response:1; }","duration":"141.541604ms","start":"2026-02-25T01:46:36.787461Z","end":"2026-02-25T01:46:36.929003Z","steps":["trace[1547259214] 'process raft request' (duration: 140.823888ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:46:52.227456Z","caller":"traceutil/trace.go:171","msg":"trace[1213349734] transaction","detail":"{read_only:false; response_revision:1767; number_of_response:1; }","duration":"120.355208ms","start":"2026-02-25T01:46:52.107054Z","end":"2026-02-25T01:46:52.22741Z","steps":["trace[1213349734] 'process raft request' (duration: 66.915342ms)","trace[1213349734] 'compare' (duration: 53.276002ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:47:19.672651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.416418ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:47:19.672794Z","caller":"traceutil/trace.go:171","msg":"trace[208404948] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1914; }","duration":"343.612923ms","start":"2026-02-25T01:47:19.329159Z","end":"2026-02-25T01:47:19.672772Z","steps":["trace[208404948] 'range keys from in-memory index tree' (duration: 343.386528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:19.672902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.984034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:47:19.672965Z","caller":"traceutil/trace.go:171","msg":"trace[811097244] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1914; }","duration":"402.105097ms","start":"2026-02-25T01:47:19.270844Z","end":"2026-02-25T01:47:19.672949Z","steps":["trace[811097244] 'count revisions from in-memory index tree' (duration: 401.890522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:19.673013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:47:19.270817Z","time spent":"402.183018ms","remote":"127.0.0.1:33102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":31,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:47:25.942797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.88133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:47:25.942899Z","caller":"traceutil/trace.go:171","msg":"trace[1344410803] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1948; }","duration":"330.027364ms","start":"2026-02-25T01:47:25.612845Z","end":"2026-02-25T01:47:25.942872Z","steps":["trace[1344410803] 'count revisions from in-memory index tree' (duration: 329.796599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:25.942957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:47:25.612796Z","time spent":"330.145376ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":12,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:47:26.495097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.184897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-25T01:47:26.495205Z","caller":"traceutil/trace.go:171","msg":"trace[499442425] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1949; }","duration":"210.33787ms","start":"2026-02-25T01:47:26.284846Z","end":"2026-02-25T01:47:26.495184Z","steps":["trace[499442425] 'range keys from in-memory index tree' (duration: 209.944791ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:47:26.643533Z","caller":"traceutil/trace.go:171","msg":"trace[19149347] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"144.338711ms","start":"2026-02-25T01:47:26.499164Z","end":"2026-02-25T01:47:26.643503Z","steps":["trace[19149347] 'process raft request' (duration: 143.916671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:27.035411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.190091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:47:27.035522Z","caller":"traceutil/trace.go:171","msg":"trace[346683179] linearizableReadLoop","detail":"{readStateIndex:2039; appliedIndex:2038; }","duration":"385.317072ms","start":"2026-02-25T01:47:26.650186Z","end":"2026-02-25T01:47:27.035503Z","steps":["trace[346683179] 'read index received' (duration: 179.868965ms)","trace[346683179] 'applied index is now lower than readState.Index' (duration: 205.446437ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:47:27.035616Z","caller":"traceutil/trace.go:171","msg":"trace[62181980] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"387.521534ms","start":"2026-02-25T01:47:26.648064Z","end":"2026-02-25T01:47:27.035586Z","steps":["trace[62181980] 'process raft request' (duration: 182.067267ms)","trace[62181980] 'compare' (duration: 205.031217ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:47:27.035701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.508867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-25T01:47:27.035742Z","caller":"traceutil/trace.go:171","msg":"trace[975920858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1951; }","duration":"385.578118ms","start":"2026-02-25T01:47:26.650151Z","end":"2026-02-25T01:47:27.03573Z","steps":["trace[975920858] 'agreement among raft nodes before linearized reading' (duration: 385.446935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:27.035747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:47:26.648012Z","time spent":"387.663047ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:47:27.035784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:47:26.650135Z","time spent":"385.64115ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T01:47:27.035866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.668403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-25T01:47:27.035902Z","caller":"traceutil/trace.go:171","msg":"trace[1080717319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1951; }","duration":"383.705844ms","start":"2026-02-25T01:47:26.652187Z","end":"2026-02-25T01:47:27.035893Z","steps":["trace[1080717319] 'agreement among raft nodes before linearized reading' (duration: 383.576781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:27.035925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:47:26.652171Z","time spent":"383.749345ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T01:47:27.03603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.0833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:47:27.036052Z","caller":"traceutil/trace.go:171","msg":"trace[1012700205] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1951; }","duration":"114.135501ms","start":"2026-02-25T01:47:26.921911Z","end":"2026-02-25T01:47:27.036047Z","steps":["trace[1012700205] 'agreement among raft nodes before linearized reading' (duration: 114.068609ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:47:27.26454Z","caller":"traceutil/trace.go:171","msg":"trace[1956513780] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"220.181503ms","start":"2026-02-25T01:47:27.044316Z","end":"2026-02-25T01:47:27.264497Z","steps":["trace[1956513780] 'process raft request' (duration: 184.241058ms)","trace[1956513780] 'compare' (duration: 35.627637ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:47:27.26519Z","caller":"traceutil/trace.go:171","msg":"trace[679195644] transaction","detail":"{read_only:false; response_revision:1953; number_of_response:1; }","duration":"220.5109ms","start":"2026-02-25T01:47:27.044649Z","end":"2026-02-25T01:47:27.265159Z","steps":["trace[679195644] 'process raft request' (duration: 219.688511ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:47:27.265225Z","caller":"traceutil/trace.go:171","msg":"trace[1921381556] linearizableReadLoop","detail":"{readStateIndex:2042; appliedIndex:2039; }","duration":"219.741632ms","start":"2026-02-25T01:47:27.045463Z","end":"2026-02-25T01:47:27.265205Z","steps":["trace[1921381556] 'read index received' (duration: 183.092411ms)","trace[1921381556] 'applied index is now lower than readState.Index' (duration: 36.648061ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:47:27.265423Z","caller":"traceutil/trace.go:171","msg":"trace[863557817] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"220.557581ms","start":"2026-02-25T01:47:27.044851Z","end":"2026-02-25T01:47:27.265409Z","steps":["trace[863557817] 'process raft request' (duration: 220.231234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:27.265829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.362077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-25T01:47:27.265885Z","caller":"traceutil/trace.go:171","msg":"trace[74005075] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1954; }","duration":"220.450749ms","start":"2026-02-25T01:47:27.045419Z","end":"2026-02-25T01:47:27.26587Z","steps":["trace[74005075] 'agreement among raft nodes before linearized reading' (duration: 220.329766ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:47:28.354432Z","caller":"traceutil/trace.go:171","msg":"trace[1313882217] linearizableReadLoop","detail":"{readStateIndex:2050; appliedIndex:2049; }","duration":"111.385277ms","start":"2026-02-25T01:47:28.243024Z","end":"2026-02-25T01:47:28.354409Z","steps":["trace[1313882217] 'read index received' (duration: 111.171702ms)","trace[1313882217] 'applied index is now lower than readState.Index' (duration: 212.645µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:47:28.354578Z","caller":"traceutil/trace.go:171","msg":"trace[436434765] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"117.181293ms","start":"2026-02-25T01:47:28.237337Z","end":"2026-02-25T01:47:28.354518Z","steps":["trace[436434765] 'process raft request' (duration: 116.931758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:28.354844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.802637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T01:47:28.354951Z","caller":"traceutil/trace.go:171","msg":"trace[1145710267] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1962; }","duration":"111.981811ms","start":"2026-02-25T01:47:28.242954Z","end":"2026-02-25T01:47:28.354936Z","steps":["trace[1145710267] 'agreement among raft nodes before linearized reading' (duration: 111.549261ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:47:31.429245Z","caller":"traceutil/trace.go:171","msg":"trace[669797842] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"135.77272ms","start":"2026-02-25T01:47:31.293445Z","end":"2026-02-25T01:47:31.429217Z","steps":["trace[669797842] 'process raft request' (duration: 95.538675ms)","trace[669797842] 'compare' (duration: 40.069211ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:47:31.681577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.137234ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:47:31.681684Z","caller":"traceutil/trace.go:171","msg":"trace[180569584] linearizableReadLoop","detail":"{readStateIndex:2066; appliedIndex:2065; }","duration":"114.9266ms","start":"2026-02-25T01:47:31.566744Z","end":"2026-02-25T01:47:31.68167Z","steps":["trace[180569584] 'read index received' (duration: 80.522µs)","trace[180569584] 'applied index is now lower than readState.Index' (duration: 114.845088ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:47:31.681758Z","caller":"traceutil/trace.go:171","msg":"trace[2050407534] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"192.80887ms","start":"2026-02-25T01:47:31.488939Z","end":"2026-02-25T01:47:31.681747Z","steps":["trace[2050407534] 'process raft request' (duration: 63.648275ms)","trace[2050407534] 'compare' (duration: 128.792326ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:47:31.68202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.281109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:47:31.682049Z","caller":"traceutil/trace.go:171","msg":"trace[281261319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1977; }","duration":"115.33643ms","start":"2026-02-25T01:47:31.566704Z","end":"2026-02-25T01:47:31.68204Z","steps":["trace[281261319] 'agreement among raft nodes before linearized reading' (duration: 115.288419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:32.696214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.37484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:47:32.696308Z","caller":"traceutil/trace.go:171","msg":"trace[1853873796] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1982; }","duration":"132.498802ms","start":"2026-02-25T01:47:32.563788Z","end":"2026-02-25T01:47:32.696287Z","steps":["trace[1853873796] 'range keys from in-memory index tree' (duration: 132.328808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:33.530993Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724526911548395,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T01:47:33.661639Z","caller":"traceutil/trace.go:171","msg":"trace[817985714] linearizableReadLoop","detail":"{readStateIndex:2073; appliedIndex:2072; }","duration":"631.482125ms","start":"2026-02-25T01:47:33.030129Z","end":"2026-02-25T01:47:33.661611Z","steps":["trace[817985714] 'read index received' (duration: 631.219599ms)","trace[817985714] 'applied index is now lower than readState.Index' (duration: 261.236µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:47:33.6618Z","caller":"traceutil/trace.go:171","msg":"trace[1389193491] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"634.20941ms","start":"2026-02-25T01:47:33.027483Z","end":"2026-02-25T01:47:33.661693Z","steps":["trace[1389193491] 'process raft request' (duration: 633.923963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:33.661851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.388477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:auth-delegator\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-02-25T01:47:33.661905Z","caller":"traceutil/trace.go:171","msg":"trace[1573246936] range","detail":"{range_begin:/registry/clusterroles/system:auth-delegator; range_end:; response_count:1; response_revision:1984; }","duration":"595.49658ms","start":"2026-02-25T01:47:33.066394Z","end":"2026-02-25T01:47:33.66189Z","steps":["trace[1573246936] 'agreement among raft nodes before linearized reading' (duration: 595.379637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:33.661956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:47:33.066374Z","time spent":"595.569272ms","remote":"127.0.0.1:33072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":675,"request content":"key:\"/registry/clusterroles/system:auth-delegator\" "} {"level":"warn","ts":"2026-02-25T01:47:33.661989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.30064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:47:33.662086Z","caller":"traceutil/trace.go:171","msg":"trace[1820900841] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1984; }","duration":"167.465384ms","start":"2026-02-25T01:47:33.494597Z","end":"2026-02-25T01:47:33.662062Z","steps":["trace[1820900841] 'agreement among raft nodes before linearized reading' (duration: 167.263949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:33.662231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.077229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:1 size:1930"} {"level":"info","ts":"2026-02-25T01:47:33.662421Z","caller":"traceutil/trace.go:171","msg":"trace[335168348] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:1; response_revision:1984; }","duration":"632.225983ms","start":"2026-02-25T01:47:33.030077Z","end":"2026-02-25T01:47:33.662303Z","steps":["trace[335168348] 'agreement among raft nodes before linearized reading' (duration: 631.67576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:33.662237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.853489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-25T01:47:33.662537Z","caller":"traceutil/trace.go:171","msg":"trace[1678509455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1984; }","duration":"356.225559ms","start":"2026-02-25T01:47:33.306297Z","end":"2026-02-25T01:47:33.662523Z","steps":["trace[1678509455] 'agreement among raft nodes before linearized reading' (duration: 355.611694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:33.662585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:47:33.306273Z","time spent":"356.29925ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T01:47:33.662662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:47:33.030054Z","time spent":"632.52094ms","remote":"127.0.0.1:33072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":1954,"request content":"key:\"/registry/clusterroles/system:aggregate-to-view\" "} {"level":"warn","ts":"2026-02-25T01:47:33.66266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:47:33.027458Z","time spent":"634.425035ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:47:33.662816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.003004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-25T01:47:33.662941Z","caller":"traceutil/trace.go:171","msg":"trace[648588567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1984; }","duration":"230.192678ms","start":"2026-02-25T01:47:33.432726Z","end":"2026-02-25T01:47:33.662919Z","steps":["trace[648588567] 'agreement among raft nodes before linearized reading' (duration: 229.84721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:33.662878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.661065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T01:47:33.663685Z","caller":"traceutil/trace.go:171","msg":"trace[381358109] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1984; }","duration":"545.521815ms","start":"2026-02-25T01:47:33.118138Z","end":"2026-02-25T01:47:33.66366Z","steps":["trace[381358109] 'agreement among raft nodes before linearized reading' (duration: 544.189144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:33.663818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:47:33.118114Z","time spent":"545.68755ms","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T01:47:33.857321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.043447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:47:33.857536Z","caller":"traceutil/trace.go:171","msg":"trace[1634049236] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"189.817131ms","start":"2026-02-25T01:47:33.667698Z","end":"2026-02-25T01:47:33.857515Z","steps":["trace[1634049236] 'process raft request' (duration: 189.733199ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:47:33.857554Z","caller":"traceutil/trace.go:171","msg":"trace[881281267] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"190.673401ms","start":"2026-02-25T01:47:33.666841Z","end":"2026-02-25T01:47:33.857514Z","steps":["trace[881281267] 'process raft request' (duration: 77.360017ms)","trace[881281267] 'compare' (duration: 112.936555ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:47:33.857703Z","caller":"traceutil/trace.go:171","msg":"trace[2048697254] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"189.422701ms","start":"2026-02-25T01:47:33.668251Z","end":"2026-02-25T01:47:33.857674Z","steps":["trace[2048697254] 'process raft request' (duration: 189.231027ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:47:33.85769Z","caller":"traceutil/trace.go:171","msg":"trace[1312428501] linearizableReadLoop","detail":"{readStateIndex:2076; appliedIndex:2073; }","duration":"156.651921ms","start":"2026-02-25T01:47:33.700956Z","end":"2026-02-25T01:47:33.857608Z","steps":["trace[1312428501] 'read index received' (duration: 43.311057ms)","trace[1312428501] 'applied index is now lower than readState.Index' (duration: 113.339124ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:47:33.858082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.108372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-02-25T01:47:33.858167Z","caller":"traceutil/trace.go:171","msg":"trace[55392847] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1987; }","duration":"157.235164ms","start":"2026-02-25T01:47:33.700911Z","end":"2026-02-25T01:47:33.858146Z","steps":["trace[55392847] 'agreement among raft nodes before linearized reading' (duration: 157.002449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:34.142131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.2096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:47:34.142247Z","caller":"traceutil/trace.go:171","msg":"trace[254380045] linearizableReadLoop","detail":"{readStateIndex:2077; appliedIndex:2076; }","duration":"210.69046ms","start":"2026-02-25T01:47:33.931539Z","end":"2026-02-25T01:47:34.142229Z","steps":["trace[254380045] 'read index received' (duration: 28.179482ms)","trace[254380045] 'applied index is now lower than readState.Index' (duration: 182.509478ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:47:34.142289Z","caller":"traceutil/trace.go:171","msg":"trace[827557013] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"278.488313ms","start":"2026-02-25T01:47:33.863763Z","end":"2026-02-25T01:47:34.142252Z","steps":["trace[827557013] 'process raft request' (duration: 96.023156ms)","trace[827557013] 'compare' (duration: 182.082078ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:47:34.142379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.836463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:basic-user\" ","response":"range_response_count:1 size:678"} {"level":"info","ts":"2026-02-25T01:47:34.142431Z","caller":"traceutil/trace.go:171","msg":"trace[914949430] range","detail":"{range_begin:/registry/clusterroles/system:basic-user; range_end:; response_count:1; response_revision:1988; }","duration":"210.914026ms","start":"2026-02-25T01:47:33.931508Z","end":"2026-02-25T01:47:34.142422Z","steps":["trace[914949430] 'agreement among raft nodes before linearized reading' (duration: 210.826124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:34.142445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.994866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient\" ","response":"range_response_count:1 size:706"} {"level":"info","ts":"2026-02-25T01:47:34.142535Z","caller":"traceutil/trace.go:171","msg":"trace[1732794495] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient; range_end:; response_count:1; response_revision:1988; }","duration":"205.108619ms","start":"2026-02-25T01:47:33.937405Z","end":"2026-02-25T01:47:34.142514Z","steps":["trace[1732794495] 'agreement among raft nodes before linearized reading' (duration: 204.948285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:34.407637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.386569ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:47:34.407717Z","caller":"traceutil/trace.go:171","msg":"trace[1770410129] linearizableReadLoop","detail":"{readStateIndex:2078; appliedIndex:2077; }","duration":"195.538754ms","start":"2026-02-25T01:47:34.212167Z","end":"2026-02-25T01:47:34.407706Z","steps":["trace[1770410129] 'read index received' (duration: 42.935489ms)","trace[1770410129] 'applied index is now lower than readState.Index' (duration: 152.602385ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:47:34.407806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.745904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:basic-user\" ","response":"range_response_count:1 size:678"} {"level":"info","ts":"2026-02-25T01:47:34.407766Z","caller":"traceutil/trace.go:171","msg":"trace[746459729] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"255.82512ms","start":"2026-02-25T01:47:34.151907Z","end":"2026-02-25T01:47:34.407732Z","steps":["trace[746459729] 'process raft request' (duration: 103.278287ms)","trace[746459729] 'compare' (duration: 152.288037ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:47:34.407826Z","caller":"traceutil/trace.go:171","msg":"trace[803378102] range","detail":"{range_begin:/registry/clusterroles/system:basic-user; range_end:; response_count:1; response_revision:1989; }","duration":"188.786295ms","start":"2026-02-25T01:47:34.219033Z","end":"2026-02-25T01:47:34.407819Z","steps":["trace[803378102] 'agreement among raft nodes before linearized reading' (duration: 188.741324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:34.407848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.662176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:47:34.407935Z","caller":"traceutil/trace.go:171","msg":"trace[2105221668] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1989; }","duration":"195.80134ms","start":"2026-02-25T01:47:34.212115Z","end":"2026-02-25T01:47:34.407916Z","steps":["trace[2105221668] 'agreement among raft nodes before linearized reading' (duration: 195.636516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:34.40802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.520112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient\" ","response":"range_response_count:1 size:706"} {"level":"info","ts":"2026-02-25T01:47:34.408069Z","caller":"traceutil/trace.go:171","msg":"trace[1620103922] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient; range_end:; response_count:1; response_revision:1989; }","duration":"186.628585ms","start":"2026-02-25T01:47:34.221427Z","end":"2026-02-25T01:47:34.408056Z","steps":["trace[1620103922] 'agreement among raft nodes before linearized reading' (duration: 186.529713ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:47:35.666582Z","caller":"traceutil/trace.go:171","msg":"trace[1554600268] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"208.018337ms","start":"2026-02-25T01:47:35.458532Z","end":"2026-02-25T01:47:35.666551Z","steps":["trace[1554600268] 'process raft request' (duration: 207.849423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:35.666595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.001705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:daemon-set-controller\" ","response":"range_response_count:1 size:958"} {"level":"warn","ts":"2026-02-25T01:47:35.666718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.364733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T01:47:35.666773Z","caller":"traceutil/trace.go:171","msg":"trace[1038444166] range","detail":"{range_begin:/registry/clusterroles/system:controller:daemon-set-controller; range_end:; response_count:1; response_revision:1992; }","duration":"276.201319ms","start":"2026-02-25T01:47:35.390547Z","end":"2026-02-25T01:47:35.666748Z","steps":["trace[1038444166] 'range keys from in-memory index tree' (duration: 275.81877ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:47:35.666818Z","caller":"traceutil/trace.go:171","msg":"trace[1076708336] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:1992; }","duration":"296.464485ms","start":"2026-02-25T01:47:35.370332Z","end":"2026-02-25T01:47:35.666797Z","steps":["trace[1076708336] 'range keys from in-memory index tree' (duration: 296.014585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:35.666828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.102757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:deployment-controller\" ","response":"range_response_count:1 size:913"} {"level":"info","ts":"2026-02-25T01:47:35.666901Z","caller":"traceutil/trace.go:171","msg":"trace[336793393] range","detail":"{range_begin:/registry/clusterroles/system:controller:deployment-controller; range_end:; response_count:1; response_revision:1992; }","duration":"299.23685ms","start":"2026-02-25T01:47:35.367641Z","end":"2026-02-25T01:47:35.666878Z","steps":["trace[336793393] 'range keys from in-memory index tree' (duration: 298.627266ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:47:35.874872Z","caller":"traceutil/trace.go:171","msg":"trace[1018307346] linearizableReadLoop","detail":"{readStateIndex:2085; appliedIndex:2084; }","duration":"135.599266ms","start":"2026-02-25T01:47:35.739247Z","end":"2026-02-25T01:47:35.874846Z","steps":["trace[1018307346] 'read index received' (duration: 14.171593ms)","trace[1018307346] 'applied index is now lower than readState.Index' (duration: 121.426633ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:47:35.874901Z","caller":"traceutil/trace.go:171","msg":"trace[1767502562] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"151.210282ms","start":"2026-02-25T01:47:35.723671Z","end":"2026-02-25T01:47:35.874881Z","steps":["trace[1767502562] 'process raft request' (duration: 110.26162ms)","trace[1767502562] 'compare' (duration: 40.786199ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:47:35.875064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.502921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-02-25T01:47:35.875078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.812871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:deployment-controller\" ","response":"range_response_count:1 size:913"} {"level":"info","ts":"2026-02-25T01:47:35.875153Z","caller":"traceutil/trace.go:171","msg":"trace[1602831190] range","detail":"{range_begin:/registry/clusterroles/system:controller:deployment-controller; range_end:; response_count:1; response_revision:1995; }","duration":"135.891312ms","start":"2026-02-25T01:47:35.739243Z","end":"2026-02-25T01:47:35.875134Z","steps":["trace[1602831190] 'agreement among raft nodes before linearized reading' (duration: 135.743729ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:47:35.875106Z","caller":"traceutil/trace.go:171","msg":"trace[2020480002] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1995; }","duration":"120.590433ms","start":"2026-02-25T01:47:35.754504Z","end":"2026-02-25T01:47:35.875095Z","steps":["trace[2020480002] 'agreement among raft nodes before linearized reading' (duration: 120.491151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:36.175242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.627088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:47:36.175413Z","caller":"traceutil/trace.go:171","msg":"trace[70866269] linearizableReadLoop","detail":"{readStateIndex:2087; appliedIndex:2085; }","duration":"293.843553ms","start":"2026-02-25T01:47:35.881552Z","end":"2026-02-25T01:47:36.175395Z","steps":["trace[70866269] 'read index received' (duration: 21.000673ms)","trace[70866269] 'applied index is now lower than readState.Index' (duration: 272.84172ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:47:36.175434Z","caller":"traceutil/trace.go:171","msg":"trace[2088025300] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"294.967819ms","start":"2026-02-25T01:47:35.880429Z","end":"2026-02-25T01:47:36.175397Z","steps":["trace[2088025300] 'process raft request' (duration: 127.087385ms)","trace[2088025300] 'compare' (duration: 167.488115ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:47:36.175519Z","caller":"traceutil/trace.go:171","msg":"trace[1717552731] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"293.895634ms","start":"2026-02-25T01:47:35.881584Z","end":"2026-02-25T01:47:36.175479Z","steps":["trace[1717552731] 'process raft request' (duration: 293.754601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:36.175599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.039198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/csi-fe14d357e2dcdb41f565ff6f7797213f2048148340a2d2edfbead0f31d89d1a6\" ","response":"range_response_count:1 size:653"} {"level":"info","ts":"2026-02-25T01:47:36.175635Z","caller":"traceutil/trace.go:171","msg":"trace[137214710] range","detail":"{range_begin:/registry/volumeattachments/csi-fe14d357e2dcdb41f565ff6f7797213f2048148340a2d2edfbead0f31d89d1a6; range_end:; response_count:1; response_revision:1998; }","duration":"294.088288ms","start":"2026-02-25T01:47:35.881535Z","end":"2026-02-25T01:47:36.175623Z","steps":["trace[137214710] 'agreement among raft nodes before linearized reading' (duration: 293.959485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:36.175637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.534776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:deployment-controller\" ","response":"range_response_count:1 size:913"} {"level":"warn","ts":"2026-02-25T01:47:36.175696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.443006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:disruption-controller\" ","response":"range_response_count:1 size:972"} {"level":"info","ts":"2026-02-25T01:47:36.175719Z","caller":"traceutil/trace.go:171","msg":"trace[733674610] range","detail":"{range_begin:/registry/clusterroles/system:controller:deployment-controller; range_end:; response_count:1; response_revision:1998; }","duration":"293.628448ms","start":"2026-02-25T01:47:35.88207Z","end":"2026-02-25T01:47:36.175699Z","steps":["trace[733674610] 'agreement among raft nodes before linearized reading' (duration: 293.468474ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:47:36.175764Z","caller":"traceutil/trace.go:171","msg":"trace[1362215775] range","detail":"{range_begin:/registry/clusterroles/system:controller:disruption-controller; range_end:; response_count:1; response_revision:1998; }","duration":"228.545819ms","start":"2026-02-25T01:47:35.947197Z","end":"2026-02-25T01:47:36.175742Z","steps":["trace[1362215775] 'agreement among raft nodes before linearized reading' (duration: 228.402136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:36.497784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.811097ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-25T01:47:36.4979Z","caller":"traceutil/trace.go:171","msg":"trace[443549740] linearizableReadLoop","detail":"{readStateIndex:2089; appliedIndex:2088; }","duration":"317.007858ms","start":"2026-02-25T01:47:36.180874Z","end":"2026-02-25T01:47:36.497882Z","steps":["trace[443549740] 'read index received' (duration: 138.897913ms)","trace[443549740] 'applied index is now lower than readState.Index' (duration: 178.108465ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:47:36.497935Z","caller":"traceutil/trace.go:171","msg":"trace[1282226131] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1999; }","duration":"319.737313ms","start":"2026-02-25T01:47:36.178174Z","end":"2026-02-25T01:47:36.497911Z","steps":["trace[1282226131] 'process raft request' (duration: 141.661229ms)","trace[1282226131] 'compare' (duration: 177.647804ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:47:36.498056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:47:36.178149Z","time spent":"319.839285ms","remote":"127.0.0.1:33102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":42,"request content":"compare: success: > failure: >"} {"level":"warn","ts":"2026-02-25T01:47:36.498118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.241444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:disruption-controller\" ","response":"range_response_count:1 size:972"} {"level":"info","ts":"2026-02-25T01:47:36.498154Z","caller":"traceutil/trace.go:171","msg":"trace[536799268] range","detail":"{range_begin:/registry/clusterroles/system:controller:disruption-controller; range_end:; response_count:1; response_revision:1999; }","duration":"317.298445ms","start":"2026-02-25T01:47:36.180845Z","end":"2026-02-25T01:47:36.498144Z","steps":["trace[536799268] 'agreement among raft nodes before linearized reading' (duration: 317.138851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:47:36.498189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:47:36.180838Z","time spent":"317.343266ms","remote":"127.0.0.1:33072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":996,"request content":"key:\"/registry/clusterroles/system:controller:disruption-controller\" "} {"level":"warn","ts":"2026-02-25T01:47:36.498188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.936999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpoint-controller\" ","response":"range_response_count:1 size:754"} {"level":"warn","ts":"2026-02-25T01:47:36.498236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.795474ms","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-25T01:47:36.498292Z","caller":"traceutil/trace.go:171","msg":"trace[11807676] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpoint-controller; range_end:; response_count:1; response_revision:1999; }","duration":"246.088142ms","start":"2026-02-25T01:47:36.25218Z","end":"2026-02-25T01:47:36.498268Z","steps":["trace[11807676] 'agreement among raft nodes before linearized reading' (duration: 245.920528ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:47:36.498323Z","caller":"traceutil/trace.go:171","msg":"trace[1987771945] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1999; }","duration":"113.938487ms","start":"2026-02-25T01:47:36.384362Z","end":"2026-02-25T01:47:36.4983Z","steps":["trace[1987771945] 'agreement among raft nodes before linearized reading' (duration: 113.787893ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:47:36.862078Z","caller":"traceutil/trace.go:171","msg":"trace[837991820] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"107.341202ms","start":"2026-02-25T01:47:36.754715Z","end":"2026-02-25T01:47:36.862056Z","steps":["trace[837991820] 'process raft request' (duration: 107.007544ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:47:37.481429Z","caller":"traceutil/trace.go:171","msg":"trace[1900294265] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"102.475408ms","start":"2026-02-25T01:47:37.378926Z","end":"2026-02-25T01:47:37.481401Z","steps":["trace[1900294265] 'process raft request' (duration: 51.595362ms)","trace[1900294265] 'compare' (duration: 50.562878ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:47:38.747443Z","caller":"traceutil/trace.go:171","msg":"trace[146966115] linearizableReadLoop","detail":"{readStateIndex:2102; appliedIndex:2101; }","duration":"117.807827ms","start":"2026-02-25T01:47:38.629598Z","end":"2026-02-25T01:47:38.747406Z","steps":["trace[146966115] 'read index received' (duration: 36.446166ms)","trace[146966115] 'applied index is now lower than readState.Index' (duration: 81.360591ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:47:38.747559Z","caller":"traceutil/trace.go:171","msg":"trace[602519055] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"143.376098ms","start":"2026-02-25T01:47:38.604148Z","end":"2026-02-25T01:47:38.747524Z","steps":["trace[602519055] 'process raft request' (duration: 61.906634ms)","trace[602519055] 'compare' (duration: 81.227458ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:47:38.747653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.023782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation\" ","response":"range_response_count:1 size:541"} {"level":"info","ts":"2026-02-25T01:47:38.747701Z","caller":"traceutil/trace.go:171","msg":"trace[1016145338] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation; range_end:; response_count:1; response_revision:2012; }","duration":"118.124265ms","start":"2026-02-25T01:47:38.629561Z","end":"2026-02-25T01:47:38.747685Z","steps":["trace[1016145338] 'agreement among raft nodes before linearized reading' (duration: 118.010322ms)"],"step_count":1}