{"level":"warn","ts":"2026-02-17T19:23:43.864845Z","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-17T19:23:43.864921Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-17T19:23:43.864985Z","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-17T19:23:43.864999Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-02-17T19:23:43.865018Z","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-17T19:23:43.865623Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-02-17T19:23:43.865709Z","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":2,"max-cpu-available":2,"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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-17T19:23:43.872773Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.235342ms"} {"level":"info","ts":"2026-02-17T19:23:43.898696Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-02-17T19:23:43.898906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T19:23:43.898964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-02-17T19:23:43.899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-17T19:23:43.899071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-02-17T19:23:43.899148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-02-17T19:23:43.909891Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T19:23:43.91669Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T19:23:43.926612Z","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-17T19:23:43.932508Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-17T19:23:43.940916Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-17T19:23:43.946832Z","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-17T19:23:43.947039Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-17T19:23:43.947064Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T19:23:43.947097Z","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-17T19:23:43.947126Z","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-17T19:23:43.947132Z","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-17T19:23:43.947207Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-02-17T19:23:43.947213Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-02-17T19:23:43.947536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-02-17T19:23:43.947634Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-02-17T19:23:44.900515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T19:23:44.900557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T19:23:44.90057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-02-17T19:23:44.900581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-02-17T19:23:44.900586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-02-17T19:23:44.9006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-02-17T19:23:44.900611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-02-17T19:23:44.902727Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:23:44.904965Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-02-17T19:23:44.905082Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T19:23:44.905285Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T19:23:44.905414Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T19:23:44.905427Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-17T19:23:44.905549Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:23:44.90559Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:23:44.905605Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:23:44.906796Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-17T19:23:44.912685Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"warn","ts":"2026-02-17T19:23:49.592132Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.014633635s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T19:23:49.592774Z","caller":"traceutil/trace.go:171","msg":"trace[2061217186] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"1.015328312s","start":"2026-02-17T19:23:48.577419Z","end":"2026-02-17T19:23:49.592748Z","steps":["trace[2061217186] 'process raft request' (duration: 1.014992774s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:49.593816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:48.577407Z","time spent":"1.015423074s","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3383,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:23:49.860691Z","caller":"traceutil/trace.go:171","msg":"trace[1830930269] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"102.693812ms","start":"2026-02-17T19:23:49.757974Z","end":"2026-02-17T19:23:49.860668Z","steps":["trace[1830930269] 'process raft request' (duration: 102.64635ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:23:49.860856Z","caller":"traceutil/trace.go:171","msg":"trace[703030952] linearizableReadLoop","detail":"{readStateIndex:217; appliedIndex:216; }","duration":"257.981355ms","start":"2026-02-17T19:23:49.602845Z","end":"2026-02-17T19:23:49.860827Z","steps":["trace[703030952] 'read index received' (duration: 214.081386ms)","trace[703030952] 'applied index is now lower than readState.Index' (duration: 43.896939ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:23:49.861078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.145799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-zqbuyx\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:23:49.861129Z","caller":"traceutil/trace.go:171","msg":"trace[210978995] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-zqbuyx; range_end:; response_count:0; response_revision:212; }","duration":"258.299024ms","start":"2026-02-17T19:23:49.602819Z","end":"2026-02-17T19:23:49.861118Z","steps":["trace[210978995] 'agreement among raft nodes before linearized reading' (duration: 258.104349ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:23:49.861166Z","caller":"traceutil/trace.go:171","msg":"trace[237374954] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"258.535019ms","start":"2026-02-17T19:23:49.602616Z","end":"2026-02-17T19:23:49.861151Z","steps":["trace[237374954] 'process raft request' (duration: 214.135768ms)","trace[237374954] 'compare' (duration: 43.712365ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:24:15.579192Z","caller":"traceutil/trace.go:171","msg":"trace[952667045] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"221.774742ms","start":"2026-02-17T19:24:15.357401Z","end":"2026-02-17T19:24:15.579176Z","steps":["trace[952667045] 'process raft request' (duration: 221.528837ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:24:16.374888Z","caller":"traceutil/trace.go:171","msg":"trace[701060719] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"357.405106ms","start":"2026-02-17T19:24:16.017464Z","end":"2026-02-17T19:24:16.37487Z","steps":["trace[701060719] 'process raft request' (duration: 357.294133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:16.375004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:16.017447Z","time spent":"357.490058ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:24:17.368334Z","caller":"traceutil/trace.go:171","msg":"trace[654681885] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:515; }","duration":"120.263833ms","start":"2026-02-17T19:24:17.248056Z","end":"2026-02-17T19:24:17.36832Z","steps":["trace[654681885] 'read index received' (duration: 120.14984ms)","trace[654681885] 'applied index is now lower than readState.Index' (duration: 113.473µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:24:17.368449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.376666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:24:17.368469Z","caller":"traceutil/trace.go:171","msg":"trace[997775257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:502; }","duration":"120.433577ms","start":"2026-02-17T19:24:17.248031Z","end":"2026-02-17T19:24:17.368464Z","steps":["trace[997775257] 'agreement among raft nodes before linearized reading' (duration: 120.368805ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:24:17.369052Z","caller":"traceutil/trace.go:171","msg":"trace[87283751] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"353.525702ms","start":"2026-02-17T19:24:17.015519Z","end":"2026-02-17T19:24:17.369044Z","steps":["trace[87283751] 'process raft request' (duration: 352.725962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:17.369115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:17.015504Z","time spent":"353.573694ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:24:23.173594Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742776771256186,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:24:23.674597Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742776771256186,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:24:23.785823Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.131003628s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T19:24:23.786106Z","caller":"traceutil/trace.go:171","msg":"trace[2007121802] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.131348416s","start":"2026-02-17T19:24:22.654739Z","end":"2026-02-17T19:24:23.786087Z","steps":["trace[2007121802] 'process raft request' (duration: 1.131242704s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:23.786208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:22.654724Z","time spent":"1.131427128s","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:24:24.0984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.830045ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-17T19:24:24.098489Z","caller":"traceutil/trace.go:171","msg":"trace[1650186447] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"1.425368842s","start":"2026-02-17T19:24:22.673104Z","end":"2026-02-17T19:24:24.098473Z","steps":["trace[1650186447] 'read index received' (duration: 1.113104585s)","trace[1650186447] 'applied index is now lower than readState.Index' (duration: 312.262797ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:24:24.098675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.425589428s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-17T19:24:24.098701Z","caller":"traceutil/trace.go:171","msg":"trace[231057549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"1.425643159s","start":"2026-02-17T19:24:22.673049Z","end":"2026-02-17T19:24:24.098692Z","steps":["trace[231057549] 'agreement among raft nodes before linearized reading' (duration: 1.425515066s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:24.098735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:22.673037Z","time spent":"1.42568996s","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T19:24:24.099061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.424992214s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-17T19:24:24.099091Z","caller":"traceutil/trace.go:171","msg":"trace[1826278228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"1.425044705s","start":"2026-02-17T19:24:22.674037Z","end":"2026-02-17T19:24:24.099082Z","steps":["trace[1826278228] 'agreement among raft nodes before linearized reading' (duration: 1.424916032s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:24.099116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:22.674027Z","time spent":"1.425082887s","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T19:24:24.099704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.11654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:24:24.099749Z","caller":"traceutil/trace.go:171","msg":"trace[1280877320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"848.196402ms","start":"2026-02-17T19:24:23.25154Z","end":"2026-02-17T19:24:24.099737Z","steps":["trace[1280877320] 'agreement among raft nodes before linearized reading' (duration: 848.074229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:24.099777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:23.251502Z","time spent":"848.268784ms","remote":"127.0.0.1:35734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T19:24:24.100042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.083786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wmbrx\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-17T19:24:24.100088Z","caller":"traceutil/trace.go:171","msg":"trace[1392365862] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wmbrx; range_end:; response_count:1; response_revision:517; }","duration":"811.167288ms","start":"2026-02-17T19:24:23.288908Z","end":"2026-02-17T19:24:24.100076Z","steps":["trace[1392365862] 'agreement among raft nodes before linearized reading' (duration: 810.973453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:24.100123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:23.288889Z","time spent":"811.22575ms","remote":"127.0.0.1:35934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16678,"request content":"key:\"/registry/pods/kube-system/cilium-wmbrx\" "} {"level":"info","ts":"2026-02-17T19:24:26.838436Z","caller":"traceutil/trace.go:171","msg":"trace[1474779023] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"307.019321ms","start":"2026-02-17T19:24:26.531401Z","end":"2026-02-17T19:24:26.83842Z","steps":["trace[1474779023] 'process raft request' (duration: 306.938719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:26.838526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:26.531376Z","time spent":"307.108163ms","remote":"127.0.0.1:35790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T19:24:26.84015Z","caller":"traceutil/trace.go:171","msg":"trace[1437092771] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"110.596119ms","start":"2026-02-17T19:24:26.729545Z","end":"2026-02-17T19:24:26.840141Z","steps":["trace[1437092771] 'process raft request' (duration: 110.534277ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:24:41.763167Z","caller":"traceutil/trace.go:171","msg":"trace[1842462236] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"435.970693ms","start":"2026-02-17T19:24:41.32717Z","end":"2026-02-17T19:24:41.76314Z","steps":["trace[1842462236] 'process raft request' (duration: 435.84258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:41.763345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:41.327154Z","time spent":"436.087586ms","remote":"127.0.0.1:35810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T19:24:42.675561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.399997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:24:42.675623Z","caller":"traceutil/trace.go:171","msg":"trace[90113068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:699; }","duration":"425.538191ms","start":"2026-02-17T19:24:42.250069Z","end":"2026-02-17T19:24:42.675607Z","steps":["trace[90113068] 'range keys from in-memory index tree' (duration: 425.336416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:42.67561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.901781ms","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-17T19:24:42.675658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:42.250055Z","time spent":"425.596052ms","remote":"127.0.0.1:35734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T19:24:42.675674Z","caller":"traceutil/trace.go:171","msg":"trace[1159037750] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:699; }","duration":"789.006754ms","start":"2026-02-17T19:24:41.886651Z","end":"2026-02-17T19:24:42.675657Z","steps":["trace[1159037750] 'range keys from in-memory index tree' (duration: 788.733988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:42.675703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:41.886634Z","time spent":"789.063316ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T19:24:42.787114Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742776771256537,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T19:24:43.101212Z","caller":"traceutil/trace.go:171","msg":"trace[484786777] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"842.779471ms","start":"2026-02-17T19:24:42.25841Z","end":"2026-02-17T19:24:43.101189Z","steps":["trace[484786777] 'process raft request' (duration: 842.619597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:43.101341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:42.258389Z","time spent":"842.882124ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:24:43.157683Z","caller":"traceutil/trace.go:171","msg":"trace[28169752] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"478.896178ms","start":"2026-02-17T19:24:42.678773Z","end":"2026-02-17T19:24:43.157669Z","steps":["trace[28169752] 'process raft request' (duration: 478.696594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:43.157777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:42.678759Z","time spent":"478.96388ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:24:43.233121Z","caller":"traceutil/trace.go:171","msg":"trace[1566266070] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:721; }","duration":"946.199978ms","start":"2026-02-17T19:24:42.286909Z","end":"2026-02-17T19:24:43.233109Z","steps":["trace[1566266070] 'read index received' (duration: 814.874837ms)","trace[1566266070] 'applied index is now lower than readState.Index' (duration: 131.324401ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:24:43.233365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.453874ms","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-17T19:24:43.233388Z","caller":"traceutil/trace.go:171","msg":"trace[1768232241] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:701; }","duration":"946.510554ms","start":"2026-02-17T19:24:42.28687Z","end":"2026-02-17T19:24:43.23338Z","steps":["trace[1768232241] 'agreement among raft nodes before linearized reading' (duration: 946.396121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:43.233409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:42.286843Z","time spent":"946.560885ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T19:24:43.233458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.706287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-17T19:24:43.23351Z","caller":"traceutil/trace.go:171","msg":"trace[1764842494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:701; }","duration":"943.778669ms","start":"2026-02-17T19:24:42.289717Z","end":"2026-02-17T19:24:43.233496Z","steps":["trace[1764842494] 'agreement among raft nodes before linearized reading' (duration: 943.632856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:43.233539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:42.289711Z","time spent":"943.8213ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T19:24:43.233625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.069041ms","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-17T19:24:43.233645Z","caller":"traceutil/trace.go:171","msg":"trace[1900366969] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:701; }","duration":"684.110931ms","start":"2026-02-17T19:24:42.549528Z","end":"2026-02-17T19:24:43.233639Z","steps":["trace[1900366969] 'agreement among raft nodes before linearized reading' (duration: 684.05486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:43.233662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:42.549512Z","time spent":"684.146001ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T19:24:43.233693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.927748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-17T19:24:43.233714Z","caller":"traceutil/trace.go:171","msg":"trace[1316979943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:701; }","duration":"937.961679ms","start":"2026-02-17T19:24:42.295747Z","end":"2026-02-17T19:24:43.233708Z","steps":["trace[1316979943] 'agreement among raft nodes before linearized reading' (duration: 937.906907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:43.233731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:42.29574Z","time spent":"937.986699ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T19:24:43.233749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.572534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:24:43.233766Z","caller":"traceutil/trace.go:171","msg":"trace[1429951825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:701; }","duration":"556.604784ms","start":"2026-02-17T19:24:42.677156Z","end":"2026-02-17T19:24:43.23376Z","steps":["trace[1429951825] 'agreement among raft nodes before linearized reading' (duration: 556.578743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:43.233781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:42.677144Z","time spent":"556.633725ms","remote":"127.0.0.1:35738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T19:25:03.763685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.972272ms","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-17T19:25:03.763791Z","caller":"traceutil/trace.go:171","msg":"trace[1953699364] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:762; }","duration":"418.126906ms","start":"2026-02-17T19:25:03.345641Z","end":"2026-02-17T19:25:03.763768Z","steps":["trace[1953699364] 'range keys from in-memory index tree' (duration: 417.771418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:03.763833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:03.345633Z","time spent":"418.191818ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T19:25:03.763895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.478747ms","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-17T19:25:03.763985Z","caller":"traceutil/trace.go:171","msg":"trace[744233505] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:762; }","duration":"416.59348ms","start":"2026-02-17T19:25:03.347367Z","end":"2026-02-17T19:25:03.76396Z","steps":["trace[744233505] 'range keys from in-memory index tree' (duration: 416.316883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:03.764031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:03.347338Z","time spent":"416.684122ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T19:25:03.764263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.440443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-17T19:25:03.764286Z","caller":"traceutil/trace.go:171","msg":"trace[1260742951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:762; }","duration":"433.478354ms","start":"2026-02-17T19:25:03.330801Z","end":"2026-02-17T19:25:03.764279Z","steps":["trace[1260742951] 'range keys from in-memory index tree' (duration: 433.356371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:03.7643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:03.330791Z","time spent":"433.505145ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T19:25:03.764437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.168341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-17T19:25:03.764488Z","caller":"traceutil/trace.go:171","msg":"trace[1436619179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:762; }","duration":"416.248652ms","start":"2026-02-17T19:25:03.348226Z","end":"2026-02-17T19:25:03.764475Z","steps":["trace[1436619179] 'range keys from in-memory index tree' (duration: 416.040018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:03.764526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:03.348213Z","time spent":"416.304934ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-17T19:25:04.086956Z","caller":"traceutil/trace.go:171","msg":"trace[149435727] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"523.300015ms","start":"2026-02-17T19:25:03.563631Z","end":"2026-02-17T19:25:04.086931Z","steps":["trace[149435727] 'process raft request' (duration: 523.122171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:04.087106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:03.563567Z","time spent":"523.459149ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:25:04.363115Z","caller":"traceutil/trace.go:171","msg":"trace[1519653761] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:789; }","duration":"249.297269ms","start":"2026-02-17T19:25:04.113797Z","end":"2026-02-17T19:25:04.363095Z","steps":["trace[1519653761] 'read index received' (duration: 150.713714ms)","trace[1519653761] 'applied index is now lower than readState.Index' (duration: 98.582755ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:25:04.363276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.435812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:25:04.363307Z","caller":"traceutil/trace.go:171","msg":"trace[1689318055] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:767; }","duration":"249.552785ms","start":"2026-02-17T19:25:04.113746Z","end":"2026-02-17T19:25:04.363299Z","steps":["trace[1689318055] 'agreement among raft nodes before linearized reading' (duration: 249.430232ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:25:04.363606Z","caller":"traceutil/trace.go:171","msg":"trace[784357631] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"591.748796ms","start":"2026-02-17T19:25:03.771845Z","end":"2026-02-17T19:25:04.363594Z","steps":["trace[784357631] 'process raft request' (duration: 492.72907ms)","trace[784357631] 'compare' (duration: 98.041132ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:25:04.363681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:03.771815Z","time spent":"591.816746ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:25:04.363813Z","caller":"traceutil/trace.go:171","msg":"trace[500709782] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"591.862968ms","start":"2026-02-17T19:25:03.771942Z","end":"2026-02-17T19:25:04.363804Z","steps":["trace[500709782] 'process raft request' (duration: 591.043828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:04.363858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:03.771936Z","time spent":"591.897978ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:25:04.363971Z","caller":"traceutil/trace.go:171","msg":"trace[517802531] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"589.479842ms","start":"2026-02-17T19:25:03.774483Z","end":"2026-02-17T19:25:04.363963Z","steps":["trace[517802531] 'process raft request' (duration: 588.55056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:04.364017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:03.774473Z","time spent":"589.517313ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:25:04.364128Z","caller":"traceutil/trace.go:171","msg":"trace[585785423] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"588.798016ms","start":"2026-02-17T19:25:03.775321Z","end":"2026-02-17T19:25:04.364119Z","steps":["trace[585785423] 'process raft request' (duration: 587.741951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:04.364173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:03.775312Z","time spent":"588.836706ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:25:16.943323Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742776771256919,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T19:25:17.067839Z","caller":"traceutil/trace.go:171","msg":"trace[1324738350] linearizableReadLoop","detail":"{readStateIndex:883; appliedIndex:882; }","duration":"628.525045ms","start":"2026-02-17T19:25:16.439301Z","end":"2026-02-17T19:25:17.067826Z","steps":["trace[1324738350] 'read index received' (duration: 628.406993ms)","trace[1324738350] 'applied index is now lower than readState.Index' (duration: 117.502µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:25:17.068027Z","caller":"traceutil/trace.go:171","msg":"trace[1125642964] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"648.922832ms","start":"2026-02-17T19:25:16.419099Z","end":"2026-02-17T19:25:17.068022Z","steps":["trace[1125642964] 'process raft request' (duration: 648.646405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:17.068092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:16.419077Z","time spent":"648.966974ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:25:17.072122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.892833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-17T19:25:17.072148Z","caller":"traceutil/trace.go:171","msg":"trace[1543332589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:853; }","duration":"631.929164ms","start":"2026-02-17T19:25:16.440211Z","end":"2026-02-17T19:25:17.07214Z","steps":["trace[1543332589] 'agreement among raft nodes before linearized reading' (duration: 631.848002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:17.072163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:16.440204Z","time spent":"631.955305ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T19:25:17.072317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.01764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-17T19:25:17.07233Z","caller":"traceutil/trace.go:171","msg":"trace[583259580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:853; }","duration":"633.056331ms","start":"2026-02-17T19:25:16.439269Z","end":"2026-02-17T19:25:17.072326Z","steps":["trace[583259580] 'agreement among raft nodes before linearized reading' (duration: 632.966639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:17.072342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:16.439239Z","time spent":"633.100182ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T19:25:17.072422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.219854ms","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-17T19:25:17.072434Z","caller":"traceutil/trace.go:171","msg":"trace[1471366764] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:853; }","duration":"627.246555ms","start":"2026-02-17T19:25:16.445184Z","end":"2026-02-17T19:25:17.07243Z","steps":["trace[1471366764] 'agreement among raft nodes before linearized reading' (duration: 627.211294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:17.072445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:16.445178Z","time spent":"627.264495ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T19:25:17.442606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.034316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:25:17.44278Z","caller":"traceutil/trace.go:171","msg":"trace[436630327] linearizableReadLoop","detail":"{readStateIndex:886; appliedIndex:883; }","duration":"192.108742ms","start":"2026-02-17T19:25:17.250659Z","end":"2026-02-17T19:25:17.442768Z","steps":["trace[436630327] 'read index received' (duration: 71.846621ms)","trace[436630327] 'applied index is now lower than readState.Index' (duration: 120.261371ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:25:17.442858Z","caller":"traceutil/trace.go:171","msg":"trace[1114955651] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"364.251936ms","start":"2026-02-17T19:25:17.078599Z","end":"2026-02-17T19:25:17.442851Z","steps":["trace[1114955651] 'process raft request' (duration: 364.083022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:17.442905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:17.07859Z","time spent":"364.291227ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:25:17.442984Z","caller":"traceutil/trace.go:171","msg":"trace[666651946] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"364.341598ms","start":"2026-02-17T19:25:17.078635Z","end":"2026-02-17T19:25:17.442977Z","steps":["trace[666651946] 'process raft request' (duration: 364.107623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:17.443015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:17.078631Z","time spent":"364.366409ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:25:17.442817Z","caller":"traceutil/trace.go:171","msg":"trace[474416993] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"364.84677ms","start":"2026-02-17T19:25:17.07795Z","end":"2026-02-17T19:25:17.442797Z","steps":["trace[474416993] 'process raft request' (duration: 244.515817ms)","trace[474416993] 'compare' (duration: 119.835461ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:25:17.443268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:17.077939Z","time spent":"365.224099ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:25:17.443403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.587195ms","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-17T19:25:17.44345Z","caller":"traceutil/trace.go:171","msg":"trace[206029683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:856; }","duration":"166.637206ms","start":"2026-02-17T19:25:17.276782Z","end":"2026-02-17T19:25:17.443419Z","steps":["trace[206029683] 'agreement among raft nodes before linearized reading' (duration: 166.550614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:17.443501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.831669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:25:17.443522Z","caller":"traceutil/trace.go:171","msg":"trace[390316688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:856; }","duration":"192.8863ms","start":"2026-02-17T19:25:17.250628Z","end":"2026-02-17T19:25:17.443515Z","steps":["trace[390316688] 'agreement among raft nodes before linearized reading' (duration: 192.840299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:17.894067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.93084ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:25:17.894136Z","caller":"traceutil/trace.go:171","msg":"trace[1695588606] linearizableReadLoop","detail":"{readStateIndex:887; appliedIndex:886; }","duration":"447.312919ms","start":"2026-02-17T19:25:17.446813Z","end":"2026-02-17T19:25:17.894125Z","steps":["trace[1695588606] 'read index received' (duration: 118.251295ms)","trace[1695588606] 'applied index is now lower than readState.Index' (duration: 329.060994ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:25:17.894338Z","caller":"traceutil/trace.go:171","msg":"trace[847466332] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"447.669248ms","start":"2026-02-17T19:25:17.446648Z","end":"2026-02-17T19:25:17.894318Z","steps":["trace[847466332] 'process raft request' (duration: 118.43499ms)","trace[847466332] 'compare' (duration: 328.706355ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:25:17.894382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:17.446636Z","time spent":"447.729788ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:25:17.894524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.710287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:25:17.894552Z","caller":"traceutil/trace.go:171","msg":"trace[1743904738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:857; }","duration":"447.761178ms","start":"2026-02-17T19:25:17.446777Z","end":"2026-02-17T19:25:17.894538Z","steps":["trace[1743904738] 'agreement among raft nodes before linearized reading' (duration: 447.730267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:17.894569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:17.446772Z","time spent":"447.794929ms","remote":"127.0.0.1:35738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T19:25:18.801098Z","caller":"traceutil/trace.go:171","msg":"trace[2060563928] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"250.270142ms","start":"2026-02-17T19:25:18.55081Z","end":"2026-02-17T19:25:18.80108Z","steps":["trace[2060563928] 'process raft request' (duration: 250.148119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:25.393682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.502882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:25:25.393729Z","caller":"traceutil/trace.go:171","msg":"trace[1594709670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:876; }","duration":"145.581814ms","start":"2026-02-17T19:25:25.248136Z","end":"2026-02-17T19:25:25.393718Z","steps":["trace[1594709670] 'range keys from in-memory index tree' (duration: 145.459301ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:25:25.773587Z","caller":"traceutil/trace.go:171","msg":"trace[2028394891] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:908; }","duration":"255.110394ms","start":"2026-02-17T19:25:25.518461Z","end":"2026-02-17T19:25:25.773571Z","steps":["trace[2028394891] 'read index received' (duration: 254.969811ms)","trace[2028394891] 'applied index is now lower than readState.Index' (duration: 140.063µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:25:25.77366Z","caller":"traceutil/trace.go:171","msg":"trace[1442127879] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"266.736737ms","start":"2026-02-17T19:25:25.506912Z","end":"2026-02-17T19:25:25.773648Z","steps":["trace[1442127879] 'process raft request' (duration: 266.559883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:25.773809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.33508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-17T19:25:25.773826Z","caller":"traceutil/trace.go:171","msg":"trace[1752456844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:877; }","duration":"255.385721ms","start":"2026-02-17T19:25:25.518435Z","end":"2026-02-17T19:25:25.773821Z","steps":["trace[1752456844] 'agreement among raft nodes before linearized reading' (duration: 255.302559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:25.773891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.578692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-17T19:25:25.773905Z","caller":"traceutil/trace.go:171","msg":"trace[1214737051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:877; }","duration":"251.619673ms","start":"2026-02-17T19:25:25.522282Z","end":"2026-02-17T19:25:25.773902Z","steps":["trace[1214737051] 'agreement among raft nodes before linearized reading' (duration: 251.581902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:32.330601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.882456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-17T19:25:32.330671Z","caller":"traceutil/trace.go:171","msg":"trace[1739040033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:894; }","duration":"454.985228ms","start":"2026-02-17T19:25:31.875668Z","end":"2026-02-17T19:25:32.330654Z","steps":["trace[1739040033] 'range keys from in-memory index tree' (duration: 454.732492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:32.330714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:31.875656Z","time spent":"455.04936ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T19:25:32.330885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.746793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-17T19:25:32.33093Z","caller":"traceutil/trace.go:171","msg":"trace[181349695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:894; }","duration":"454.825134ms","start":"2026-02-17T19:25:31.876094Z","end":"2026-02-17T19:25:32.330919Z","steps":["trace[181349695] 'range keys from in-memory index tree' (duration: 454.63824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:32.330949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:31.876081Z","time spent":"454.864445ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T19:25:32.330941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.284314ms","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-17T19:25:32.331002Z","caller":"traceutil/trace.go:171","msg":"trace[1216151205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:894; }","duration":"375.348755ms","start":"2026-02-17T19:25:31.955623Z","end":"2026-02-17T19:25:32.330971Z","steps":["trace[1216151205] 'range keys from in-memory index tree' (duration: 375.199582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:32.331031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:31.955611Z","time spent":"375.413187ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-17T19:25:32.531594Z","caller":"traceutil/trace.go:171","msg":"trace[2009658639] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"194.15873ms","start":"2026-02-17T19:25:32.337414Z","end":"2026-02-17T19:25:32.531573Z","steps":["trace[2009658639] 'process raft request' (duration: 194.048058ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:25:32.644212Z","caller":"traceutil/trace.go:171","msg":"trace[874234676] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"305.486672ms","start":"2026-02-17T19:25:32.338708Z","end":"2026-02-17T19:25:32.644195Z","steps":["trace[874234676] 'process raft request' (duration: 305.436581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:32.644313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:32.338697Z","time spent":"305.563224ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:25:32.644528Z","caller":"traceutil/trace.go:171","msg":"trace[180010473] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"306.472526ms","start":"2026-02-17T19:25:32.338043Z","end":"2026-02-17T19:25:32.644516Z","steps":["trace[180010473] 'process raft request' (duration: 275.769848ms)","trace[180010473] 'compare' (duration: 30.076063ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:25:32.644562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:32.338032Z","time spent":"306.511807ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:25:34.014437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.307989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:25:34.014506Z","caller":"traceutil/trace.go:171","msg":"trace[628707356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:898; }","duration":"764.409702ms","start":"2026-02-17T19:25:33.250079Z","end":"2026-02-17T19:25:34.014488Z","steps":["trace[628707356] 'range keys from in-memory index tree' (duration: 764.247998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:34.014544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:25:33.250065Z","time spent":"764.470593ms","remote":"127.0.0.1:35734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T19:25:34.017468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.01551ms","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-17T19:25:34.01751Z","caller":"traceutil/trace.go:171","msg":"trace[170673890] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:898; }","duration":"222.094692ms","start":"2026-02-17T19:25:33.795404Z","end":"2026-02-17T19:25:34.017499Z","steps":["trace[170673890] 'range keys from in-memory index tree' (duration: 220.605098ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:25:34.745968Z","caller":"traceutil/trace.go:171","msg":"trace[67513616] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"209.367515ms","start":"2026-02-17T19:25:34.536577Z","end":"2026-02-17T19:25:34.745944Z","steps":["trace[67513616] 'process raft request' (duration: 209.109349ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:25:44.921946Z","caller":"traceutil/trace.go:171","msg":"trace[970073967] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1005; }","duration":"131.673229ms","start":"2026-02-17T19:25:44.790249Z","end":"2026-02-17T19:25:44.921922Z","steps":["trace[970073967] 'read index received' (duration: 131.493455ms)","trace[970073967] 'applied index is now lower than readState.Index' (duration: 178.884µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:25:44.922341Z","caller":"traceutil/trace.go:171","msg":"trace[1851057846] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"139.809958ms","start":"2026-02-17T19:25:44.782513Z","end":"2026-02-17T19:25:44.922323Z","steps":["trace[1851057846] 'process raft request' (duration: 139.290376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:44.92264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.351905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-17T19:25:44.922683Z","caller":"traceutil/trace.go:171","msg":"trace[2087989174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:970; }","duration":"132.430466ms","start":"2026-02-17T19:25:44.790236Z","end":"2026-02-17T19:25:44.922667Z","steps":["trace[2087989174] 'agreement among raft nodes before linearized reading' (duration: 132.280663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:44.922928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.965155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-17T19:25:44.922961Z","caller":"traceutil/trace.go:171","msg":"trace[2070863718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:970; }","duration":"132.006386ms","start":"2026-02-17T19:25:44.790945Z","end":"2026-02-17T19:25:44.922952Z","steps":["trace[2070863718] 'agreement among raft nodes before linearized reading' (duration: 131.902084ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:25:45.179472Z","caller":"traceutil/trace.go:171","msg":"trace[1352863475] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"209.700223ms","start":"2026-02-17T19:25:44.969743Z","end":"2026-02-17T19:25:45.179443Z","steps":["trace[1352863475] 'process raft request' (duration: 148.535743ms)","trace[1352863475] 'compare' (duration: 60.880153ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:25:45.217481Z","caller":"traceutil/trace.go:171","msg":"trace[2121662861] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"246.764689ms","start":"2026-02-17T19:25:44.970702Z","end":"2026-02-17T19:25:45.217466Z","steps":["trace[2121662861] 'process raft request' (duration: 246.677517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:48.355774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.025278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:25:48.355842Z","caller":"traceutil/trace.go:171","msg":"trace[1998316645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1001; }","duration":"106.134661ms","start":"2026-02-17T19:25:48.249691Z","end":"2026-02-17T19:25:48.355826Z","steps":["trace[1998316645] 'range keys from in-memory index tree' (duration: 105.954387ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:28:58.134895Z","caller":"traceutil/trace.go:171","msg":"trace[1426756526] linearizableReadLoop","detail":"{readStateIndex:1880; appliedIndex:1879; }","duration":"211.099375ms","start":"2026-02-17T19:28:57.923778Z","end":"2026-02-17T19:28:58.134877Z","steps":["trace[1426756526] 'read index received' (duration: 199.540595ms)","trace[1426756526] 'applied index is now lower than readState.Index' (duration: 11.55792ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:28:58.135109Z","caller":"traceutil/trace.go:171","msg":"trace[561474713] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"220.848934ms","start":"2026-02-17T19:28:57.91425Z","end":"2026-02-17T19:28:58.135099Z","steps":["trace[561474713] 'process raft request' (duration: 209.083119ms)","trace[561474713] 'compare' (duration: 11.126861ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:28:58.135312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.518885ms","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-17T19:28:58.13534Z","caller":"traceutil/trace.go:171","msg":"trace[738056551] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1804; }","duration":"211.565496ms","start":"2026-02-17T19:28:57.923767Z","end":"2026-02-17T19:28:58.135333Z","steps":["trace[738056551] 'agreement among raft nodes before linearized reading' (duration: 211.451393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:28:58.135451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.733468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-17T19:28:58.135473Z","caller":"traceutil/trace.go:171","msg":"trace[2104577157] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1804; }","duration":"127.782329ms","start":"2026-02-17T19:28:58.007685Z","end":"2026-02-17T19:28:58.135468Z","steps":["trace[2104577157] 'agreement among raft nodes before linearized reading' (duration: 127.728708ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:28:58.28874Z","caller":"traceutil/trace.go:171","msg":"trace[1709493380] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"148.739701ms","start":"2026-02-17T19:28:58.139976Z","end":"2026-02-17T19:28:58.288716Z","steps":["trace[1709493380] 'process raft request' (duration: 119.398292ms)","trace[1709493380] 'compare' (duration: 28.94667ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:28:58.288934Z","caller":"traceutil/trace.go:171","msg":"trace[1438645961] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"148.832583ms","start":"2026-02-17T19:28:58.14009Z","end":"2026-02-17T19:28:58.288922Z","steps":["trace[1438645961] 'process raft request' (duration: 148.407014ms)"],"step_count":1}