{"level":"warn","ts":"2026-02-02T17:46:27.071889Z","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-02T17:46:27.071984Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23: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.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-02T17:46:27.072046Z","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-02T17:46:27.072058Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-02-02T17:46:27.072079Z","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-02T17:46:27.073844Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-02-02T17:46:27.075369Z","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.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-02T17:46:27.079844Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.998733ms"} {"level":"info","ts":"2026-02-02T17:46:27.114077Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-02-02T17:46:27.114147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-02-02T17:46:27.11425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-02-02T17:46:27.11426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-02T17:46:27.1176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-02-02T17:46:27.117686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-02-02T17:46:27.126324Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-02T17:46:27.130578Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-02T17:46:27.136128Z","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-02T17:46:27.142893Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-02T17:46:27.147494Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-02T17:46:27.147691Z","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-02T17:46:27.147729Z","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-02T17:46:27.14774Z","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-02T17:46:27.171703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-02-02T17:46:27.171892Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-02-02T17:46:27.174076Z","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-02T17:46:27.180621Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-02T17:46:27.180648Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-02T17:46:27.18112Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-02-02T17:46:27.181136Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-02-02T17:46:28.117996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-02-02T17:46:28.118045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-02T17:46:28.118067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-02-02T17:46:28.118078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-02-02T17:46:28.118086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-02-02T17:46:28.118103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-02-02T17:46:28.11811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-02-02T17:46:28.120178Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T17:46:28.122371Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-02-02T17:46:28.122394Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T17:46:28.124046Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-02T17:46:28.12651Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T17:46:28.128722Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-02T17:46:28.128737Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-02T17:46:28.130952Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-02-02T17:46:28.131122Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T17:46:28.131163Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T17:46:28.131207Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-02T17:46:44.446159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.272429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:46:44.446251Z","caller":"traceutil/trace.go:171","msg":"trace[1174788524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:423; }","duration":"476.397473ms","start":"2026-02-02T17:46:43.969836Z","end":"2026-02-02T17:46:44.446234Z","steps":["trace[1174788524] 'range keys from in-memory index tree' (duration: 476.222168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:46:44.446296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:46:43.969822Z","time spent":"476.464424ms","remote":"127.0.0.1:39328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-02T17:46:44.821093Z","caller":"traceutil/trace.go:171","msg":"trace[644973779] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"359.659899ms","start":"2026-02-02T17:46:44.46141Z","end":"2026-02-02T17:46:44.82107Z","steps":["trace[644973779] 'process raft request' (duration: 359.497094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:46:44.822212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:46:44.461361Z","time spent":"359.798843ms","remote":"127.0.0.1:39830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:46:48.014636Z","caller":"traceutil/trace.go:171","msg":"trace[1368765577] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"168.091897ms","start":"2026-02-02T17:46:47.846525Z","end":"2026-02-02T17:46:48.014617Z","steps":["trace[1368765577] 'process raft request' (duration: 167.960273ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:46:48.016565Z","caller":"traceutil/trace.go:171","msg":"trace[2136731769] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"168.652723ms","start":"2026-02-02T17:46:47.847903Z","end":"2026-02-02T17:46:48.016556Z","steps":["trace[2136731769] 'process raft request' (duration: 168.382685ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:46:59.809506Z","caller":"traceutil/trace.go:171","msg":"trace[1257858315] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"198.801066ms","start":"2026-02-02T17:46:59.610686Z","end":"2026-02-02T17:46:59.809487Z","steps":["trace[1257858315] 'process raft request' (duration: 198.503367ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:47:02.07653Z","caller":"traceutil/trace.go:171","msg":"trace[1772849386] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"262.20638ms","start":"2026-02-02T17:47:01.814311Z","end":"2026-02-02T17:47:02.076518Z","steps":["trace[1772849386] 'process raft request' (duration: 261.85607ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:47:02.0763Z","caller":"traceutil/trace.go:171","msg":"trace[2119254494] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:546; }","duration":"185.886501ms","start":"2026-02-02T17:47:01.890397Z","end":"2026-02-02T17:47:02.076283Z","steps":["trace[2119254494] 'read index received' (duration: 185.743437ms)","trace[2119254494] 'applied index is now lower than readState.Index' (duration: 140.414µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:47:02.077041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.632722ms","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-02T17:47:02.077082Z","caller":"traceutil/trace.go:171","msg":"trace[752868312] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"186.705744ms","start":"2026-02-02T17:47:01.890364Z","end":"2026-02-02T17:47:02.077069Z","steps":["trace[752868312] 'agreement among raft nodes before linearized reading' (duration: 186.504438ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:47:02.368154Z","caller":"traceutil/trace.go:171","msg":"trace[546552506] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"162.152169ms","start":"2026-02-02T17:47:02.205984Z","end":"2026-02-02T17:47:02.368136Z","steps":["trace[546552506] 'read index received' (duration: 161.356887ms)","trace[546552506] 'applied index is now lower than readState.Index' (duration: 793.432µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:47:02.368438Z","caller":"traceutil/trace.go:171","msg":"trace[1760815787] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"287.23179ms","start":"2026-02-02T17:47:02.081194Z","end":"2026-02-02T17:47:02.368426Z","steps":["trace[1760815787] 'process raft request' (duration: 286.272142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:02.368684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.688164ms","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-02T17:47:02.368705Z","caller":"traceutil/trace.go:171","msg":"trace[867576225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:530; }","duration":"162.751186ms","start":"2026-02-02T17:47:02.205947Z","end":"2026-02-02T17:47:02.368698Z","steps":["trace[867576225] 'agreement among raft nodes before linearized reading' (duration: 162.705205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:02.368805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.756108ms","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-02T17:47:02.368819Z","caller":"traceutil/trace.go:171","msg":"trace[566129354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:530; }","duration":"161.771519ms","start":"2026-02-02T17:47:02.207043Z","end":"2026-02-02T17:47:02.368815Z","steps":["trace[566129354] 'agreement among raft nodes before linearized reading' (duration: 161.743328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:07.147845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.873617ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-02T17:47:07.147944Z","caller":"traceutil/trace.go:171","msg":"trace[623592838] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:559; }","duration":"923.480745ms","start":"2026-02-02T17:47:06.224446Z","end":"2026-02-02T17:47:07.147927Z","steps":["trace[623592838] 'read index received' (duration: 82.297159ms)","trace[623592838] 'applied index is now lower than readState.Index' (duration: 841.182326ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:47:07.148316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.853975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6zfps\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-02T17:47:07.148368Z","caller":"traceutil/trace.go:171","msg":"trace[269368806] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6zfps; range_end:; response_count:1; response_revision:541; }","duration":"923.946238ms","start":"2026-02-02T17:47:06.224407Z","end":"2026-02-02T17:47:07.148353Z","steps":["trace[269368806] 'agreement among raft nodes before linearized reading' (duration: 923.745532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:07.148411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:47:06.224387Z","time spent":"924.01336ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16675,"request content":"key:\"/registry/pods/kube-system/cilium-6zfps\" "} {"level":"warn","ts":"2026-02-02T17:47:07.148398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.086087ms","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-02T17:47:07.148466Z","caller":"traceutil/trace.go:171","msg":"trace[751435970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:541; }","duration":"768.197561ms","start":"2026-02-02T17:47:06.380251Z","end":"2026-02-02T17:47:07.148449Z","steps":["trace[751435970] 'agreement among raft nodes before linearized reading' (duration: 767.984835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:07.148506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:47:06.38023Z","time spent":"768.267182ms","remote":"127.0.0.1:39626","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-02T17:47:07.148695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.241689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:47:07.148725Z","caller":"traceutil/trace.go:171","msg":"trace[776047263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:541; }","duration":"175.291621ms","start":"2026-02-02T17:47:06.973424Z","end":"2026-02-02T17:47:07.148715Z","steps":["trace[776047263] 'agreement among raft nodes before linearized reading' (duration: 175.2425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:07.148843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.799958ms","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-02T17:47:07.14888Z","caller":"traceutil/trace.go:171","msg":"trace[1640892543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:541; }","duration":"762.856378ms","start":"2026-02-02T17:47:06.386014Z","end":"2026-02-02T17:47:07.14887Z","steps":["trace[1640892543] 'agreement among raft nodes before linearized reading' (duration: 762.752886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:07.148909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:47:06.386002Z","time spent":"762.89854ms","remote":"127.0.0.1:39626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-02T17:47:07.149161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.80421ms","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-02T17:47:07.149189Z","caller":"traceutil/trace.go:171","msg":"trace[385135731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:541; }","duration":"761.869821ms","start":"2026-02-02T17:47:06.387311Z","end":"2026-02-02T17:47:07.149181Z","steps":["trace[385135731] 'agreement among raft nodes before linearized reading' (duration: 761.790939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:07.149214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:47:06.387291Z","time spent":"761.917522ms","remote":"127.0.0.1:39626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-02T17:47:07.606672Z","caller":"traceutil/trace.go:171","msg":"trace[2119460746] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"336.787401ms","start":"2026-02-02T17:47:07.26986Z","end":"2026-02-02T17:47:07.606648Z","steps":["trace[2119460746] 'process raft request' (duration: 336.73355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:07.606873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:47:07.269852Z","time spent":"336.934556ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:47:07.606913Z","caller":"traceutil/trace.go:171","msg":"trace[1489221348] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"343.498401ms","start":"2026-02-02T17:47:07.263375Z","end":"2026-02-02T17:47:07.606874Z","steps":["trace[1489221348] 'process raft request' (duration: 342.865524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:07.607119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:47:07.263351Z","time spent":"343.665276ms","remote":"127.0.0.1:39626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:47:07.607196Z","caller":"traceutil/trace.go:171","msg":"trace[1789513430] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"341.886566ms","start":"2026-02-02T17:47:07.265296Z","end":"2026-02-02T17:47:07.607182Z","steps":["trace[1789513430] 'process raft request' (duration: 341.243478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:07.607258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:47:07.265287Z","time spent":"341.937987ms","remote":"127.0.0.1:39626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:47:09.566224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.794396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-02T17:47:09.566966Z","caller":"traceutil/trace.go:171","msg":"trace[1895670622] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:558; }","duration":"164.562618ms","start":"2026-02-02T17:47:09.402383Z","end":"2026-02-02T17:47:09.566946Z","steps":["trace[1895670622] 'range keys from in-memory index tree' (duration: 163.687283ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:47:24.501289Z","caller":"traceutil/trace.go:171","msg":"trace[385888405] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"340.370943ms","start":"2026-02-02T17:47:24.160902Z","end":"2026-02-02T17:47:24.501273Z","steps":["trace[385888405] 'process raft request' (duration: 340.28271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:24.501394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:47:24.160879Z","time spent":"340.463375ms","remote":"127.0.0.1:39626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:47:25.29892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.52625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:47:25.29903Z","caller":"traceutil/trace.go:171","msg":"trace[931308151] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"1.102583099s","start":"2026-02-02T17:47:24.196415Z","end":"2026-02-02T17:47:25.298999Z","steps":["trace[931308151] 'process raft request' (duration: 664.889175ms)","trace[931308151] 'compare' (duration: 437.032716ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:47:25.299127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:47:24.196401Z","time spent":"1.102674852s","remote":"127.0.0.1:39626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:47:25.3062Z","caller":"traceutil/trace.go:171","msg":"trace[1545770129] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"498.021388ms","start":"2026-02-02T17:47:24.808166Z","end":"2026-02-02T17:47:25.306188Z","steps":["trace[1545770129] 'process raft request' (duration: 497.809703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:25.306378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:47:24.80815Z","time spent":"498.174063ms","remote":"127.0.0.1:39430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":815,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T17:47:25.306982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.926867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:47:25.30727Z","caller":"traceutil/trace.go:171","msg":"trace[901957548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:727; }","duration":"338.250516ms","start":"2026-02-02T17:47:24.969011Z","end":"2026-02-02T17:47:25.307261Z","steps":["trace[901957548] 'agreement among raft nodes before linearized reading' (duration: 337.913386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:25.307334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:47:24.968991Z","time spent":"338.335708ms","remote":"127.0.0.1:39328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-02T17:47:25.308297Z","caller":"traceutil/trace.go:171","msg":"trace[1154828987] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"337.035583ms","start":"2026-02-02T17:47:24.969053Z","end":"2026-02-02T17:47:25.306088Z","steps":["trace[1154828987] 'read index received' (duration: 336.848218ms)","trace[1154828987] 'applied index is now lower than readState.Index' (duration: 186.625µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:47:27.794992Z","caller":"traceutil/trace.go:171","msg":"trace[589825450] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"125.587052ms","start":"2026-02-02T17:47:27.669384Z","end":"2026-02-02T17:47:27.794971Z","steps":["trace[589825450] 'process raft request' (duration: 125.297124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:27.980299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.33124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:47:27.98057Z","caller":"traceutil/trace.go:171","msg":"trace[947958166] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"177.460174ms","start":"2026-02-02T17:47:27.803088Z","end":"2026-02-02T17:47:27.980548Z","steps":["trace[947958166] 'process raft request' (duration: 75.777255ms)","trace[947958166] 'compare' (duration: 101.254428ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:47:27.980612Z","caller":"traceutil/trace.go:171","msg":"trace[798969873] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"177.235918ms","start":"2026-02-02T17:47:27.803367Z","end":"2026-02-02T17:47:27.980603Z","steps":["trace[798969873] 'process raft request' (duration: 176.996941ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:47:29.041112Z","caller":"traceutil/trace.go:171","msg":"trace[494983059] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"526.655109ms","start":"2026-02-02T17:47:28.514438Z","end":"2026-02-02T17:47:29.041093Z","steps":["trace[494983059] 'process raft request' (duration: 526.543886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:29.041224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:47:28.514425Z","time spent":"526.735691ms","remote":"127.0.0.1:39626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:47:44.192237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.372589ms","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-02T17:47:44.192297Z","caller":"traceutil/trace.go:171","msg":"trace[1374989645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:785; }","duration":"131.470692ms","start":"2026-02-02T17:47:44.060811Z","end":"2026-02-02T17:47:44.192282Z","steps":["trace[1374989645] 'range keys from in-memory index tree' (duration: 131.244566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:44.192441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.643261ms","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-02T17:47:44.192491Z","caller":"traceutil/trace.go:171","msg":"trace[1084122881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:785; }","duration":"162.735394ms","start":"2026-02-02T17:47:44.029742Z","end":"2026-02-02T17:47:44.192477Z","steps":["trace[1084122881] 'range keys from in-memory index tree' (duration: 162.496797ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:47:45.557493Z","caller":"traceutil/trace.go:171","msg":"trace[2036796498] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"442.440134ms","start":"2026-02-02T17:47:45.115032Z","end":"2026-02-02T17:47:45.557472Z","steps":["trace[2036796498] 'process raft request' (duration: 442.107015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:45.557619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:47:45.115018Z","time spent":"442.535927ms","remote":"127.0.0.1:39626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:47:46.038461Z","caller":"traceutil/trace.go:171","msg":"trace[532400960] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"159.593379ms","start":"2026-02-02T17:47:45.878672Z","end":"2026-02-02T17:47:46.038265Z","steps":["trace[532400960] 'process raft request' (duration: 159.401103ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:47:46.202349Z","caller":"traceutil/trace.go:171","msg":"trace[1134238056] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"243.186526ms","start":"2026-02-02T17:47:45.959147Z","end":"2026-02-02T17:47:46.202334Z","steps":["trace[1134238056] 'process raft request' (duration: 234.956281ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:47:47.711907Z","caller":"traceutil/trace.go:171","msg":"trace[1032113423] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"138.02282ms","start":"2026-02-02T17:47:47.573863Z","end":"2026-02-02T17:47:47.711885Z","steps":["trace[1032113423] 'process raft request' (duration: 137.611619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:48.174037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.70316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:47:48.174647Z","caller":"traceutil/trace.go:171","msg":"trace[824583773] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"453.974269ms","start":"2026-02-02T17:47:47.720655Z","end":"2026-02-02T17:47:48.174629Z","steps":["trace[824583773] 'read index received' (duration: 267.525569ms)","trace[824583773] 'applied index is now lower than readState.Index' (duration: 186.44671ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:47:48.175069Z","caller":"traceutil/trace.go:171","msg":"trace[391203838] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"457.651889ms","start":"2026-02-02T17:47:47.717403Z","end":"2026-02-02T17:47:48.175055Z","steps":["trace[391203838] 'process raft request' (duration: 270.838689ms)","trace[391203838] 'compare' (duration: 185.541745ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:47:48.176006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:47:47.717385Z","time spent":"458.574854ms","remote":"127.0.0.1:39626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:47:48.176936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.269501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T17:47:48.177341Z","caller":"traceutil/trace.go:171","msg":"trace[2083046159] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:795; }","duration":"456.705193ms","start":"2026-02-02T17:47:47.720622Z","end":"2026-02-02T17:47:48.177327Z","steps":["trace[2083046159] 'agreement among raft nodes before linearized reading' (duration: 456.268301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:48.177449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:47:47.720606Z","time spent":"456.828157ms","remote":"127.0.0.1:39830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:47:48.177815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.084619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:47:48.177933Z","caller":"traceutil/trace.go:171","msg":"trace[576409376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"208.231413ms","start":"2026-02-02T17:47:47.96969Z","end":"2026-02-02T17:47:48.177922Z","steps":["trace[576409376] 'agreement among raft nodes before linearized reading' (duration: 208.09275ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:47:48.372502Z","caller":"traceutil/trace.go:171","msg":"trace[830126386] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"153.79377ms","start":"2026-02-02T17:47:48.218688Z","end":"2026-02-02T17:47:48.372481Z","steps":["trace[830126386] 'read index received' (duration: 80.817422ms)","trace[830126386] 'applied index is now lower than readState.Index' (duration: 72.975478ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:47:48.373121Z","caller":"traceutil/trace.go:171","msg":"trace[1109583185] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"164.306607ms","start":"2026-02-02T17:47:48.208799Z","end":"2026-02-02T17:47:48.373105Z","steps":["trace[1109583185] 'process raft request' (duration: 90.64609ms)","trace[1109583185] 'compare' (duration: 72.758242ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:47:48.373354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.608642ms","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-02T17:47:48.37343Z","caller":"traceutil/trace.go:171","msg":"trace[363209644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"154.698344ms","start":"2026-02-02T17:47:48.218711Z","end":"2026-02-02T17:47:48.373409Z","steps":["trace[363209644] 'agreement among raft nodes before linearized reading' (duration: 154.446617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:48.37375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.054594ms","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-02T17:47:48.373791Z","caller":"traceutil/trace.go:171","msg":"trace[342319276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"155.107755ms","start":"2026-02-02T17:47:48.218674Z","end":"2026-02-02T17:47:48.373781Z","steps":["trace[342319276] 'agreement among raft nodes before linearized reading' (duration: 155.008933ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:48:00.577073Z","caller":"traceutil/trace.go:171","msg":"trace[1268936891] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"347.256512ms","start":"2026-02-02T17:48:00.229798Z","end":"2026-02-02T17:48:00.577055Z","steps":["trace[1268936891] 'process raft request' (duration: 347.099518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:48:00.577222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:48:00.22979Z","time spent":"347.355454ms","remote":"127.0.0.1:39626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:48:00.57763Z","caller":"traceutil/trace.go:171","msg":"trace[399053377] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:912; }","duration":"231.919018ms","start":"2026-02-02T17:48:00.345704Z","end":"2026-02-02T17:48:00.577623Z","steps":["trace[399053377] 'read index received' (duration: 231.915948ms)","trace[399053377] 'applied index is now lower than readState.Index' (duration: 2.56µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:48:00.577763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.748839ms","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-02T17:48:00.577785Z","caller":"traceutil/trace.go:171","msg":"trace[2058139600] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:880; }","duration":"153.79378ms","start":"2026-02-02T17:48:00.423983Z","end":"2026-02-02T17:48:00.577777Z","steps":["trace[2058139600] 'agreement among raft nodes before linearized reading' (duration: 153.698868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:48:00.577916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.208086ms","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-02T17:48:00.577936Z","caller":"traceutil/trace.go:171","msg":"trace[2004983054] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:880; }","duration":"232.254368ms","start":"2026-02-02T17:48:00.345677Z","end":"2026-02-02T17:48:00.577931Z","steps":["trace[2004983054] 'agreement among raft nodes before linearized reading' (duration: 232.217457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:48:00.578201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.547032ms","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-02T17:48:00.578221Z","caller":"traceutil/trace.go:171","msg":"trace[894178389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:880; }","duration":"128.596083ms","start":"2026-02-02T17:48:00.449618Z","end":"2026-02-02T17:48:00.578214Z","steps":["trace[894178389] 'agreement among raft nodes before linearized reading' (duration: 128.531112ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:48:00.725925Z","caller":"traceutil/trace.go:171","msg":"trace[1767655642] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"137.918678ms","start":"2026-02-02T17:48:00.587973Z","end":"2026-02-02T17:48:00.725892Z","steps":["trace[1767655642] 'process raft request' (duration: 137.890477ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:48:00.726008Z","caller":"traceutil/trace.go:171","msg":"trace[2090140983] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"142.500463ms","start":"2026-02-02T17:48:00.583487Z","end":"2026-02-02T17:48:00.725988Z","steps":["trace[2090140983] 'process raft request' (duration: 133.112847ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:48:00.72611Z","caller":"traceutil/trace.go:171","msg":"trace[378929530] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"141.969808ms","start":"2026-02-02T17:48:00.584134Z","end":"2026-02-02T17:48:00.726104Z","steps":["trace[378929530] 'process raft request' (duration: 141.6974ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:48:01.976871Z","caller":"traceutil/trace.go:171","msg":"trace[641579203] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"209.784955ms","start":"2026-02-02T17:48:01.767073Z","end":"2026-02-02T17:48:01.976858Z","steps":["trace[641579203] 'process raft request' (duration: 209.59209ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:48:02.958049Z","caller":"traceutil/trace.go:171","msg":"trace[1785530918] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"165.89302ms","start":"2026-02-02T17:48:02.79214Z","end":"2026-02-02T17:48:02.958033Z","steps":["trace[1785530918] 'process raft request' (duration: 165.865999ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:48:02.958341Z","caller":"traceutil/trace.go:171","msg":"trace[658155162] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"166.613459ms","start":"2026-02-02T17:48:02.791722Z","end":"2026-02-02T17:48:02.958336Z","steps":["trace[658155162] 'process raft request' (duration: 166.197858ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:48:14.858283Z","caller":"traceutil/trace.go:171","msg":"trace[723470537] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"233.377278ms","start":"2026-02-02T17:48:14.624891Z","end":"2026-02-02T17:48:14.858268Z","steps":["trace[723470537] 'process raft request' (duration: 233.306096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:48:15.130073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.026064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:48:15.130142Z","caller":"traceutil/trace.go:171","msg":"trace[1052370252] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"160.193294ms","start":"2026-02-02T17:48:14.969938Z","end":"2026-02-02T17:48:15.130131Z","steps":["trace[1052370252] 'read index received' (duration: 44.811µs)","trace[1052370252] 'applied index is now lower than readState.Index' (duration: 160.147683ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:48:15.130291Z","caller":"traceutil/trace.go:171","msg":"trace[1817530461] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"265.653288ms","start":"2026-02-02T17:48:14.86463Z","end":"2026-02-02T17:48:15.130284Z","steps":["trace[1817530461] 'process raft request' (duration: 74.352936ms)","trace[1817530461] 'compare' (duration: 190.778367ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:48:15.130469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.518813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:48:15.130527Z","caller":"traceutil/trace.go:171","msg":"trace[400913203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:921; }","duration":"160.605616ms","start":"2026-02-02T17:48:14.969899Z","end":"2026-02-02T17:48:15.130505Z","steps":["trace[400913203] 'agreement among raft nodes before linearized reading' (duration: 160.503863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:48:15.130529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.694535ms","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-02T17:48:15.130552Z","caller":"traceutil/trace.go:171","msg":"trace[1436839930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:921; }","duration":"114.753307ms","start":"2026-02-02T17:48:15.015792Z","end":"2026-02-02T17:48:15.130545Z","steps":["trace[1436839930] 'agreement among raft nodes before linearized reading' (duration: 114.673534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:48:15.130685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.88748ms","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-02T17:48:15.130706Z","caller":"traceutil/trace.go:171","msg":"trace[1885171775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:921; }","duration":"103.909181ms","start":"2026-02-02T17:48:15.026792Z","end":"2026-02-02T17:48:15.130701Z","steps":["trace[1885171775] 'agreement among raft nodes before linearized reading' (duration: 103.857199ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:48:21.101608Z","caller":"traceutil/trace.go:171","msg":"trace[1632594005] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"195.298926ms","start":"2026-02-02T17:48:20.906285Z","end":"2026-02-02T17:48:21.101584Z","steps":["trace[1632594005] 'process raft request' (duration: 195.039229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:48:21.604422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.940621ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-02T17:48:21.604561Z","caller":"traceutil/trace.go:171","msg":"trace[1788089458] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:999; }","duration":"477.200921ms","start":"2026-02-02T17:48:21.127328Z","end":"2026-02-02T17:48:21.604528Z","steps":["trace[1788089458] 'read index received' (duration: 140.66431ms)","trace[1788089458] 'applied index is now lower than readState.Index' (duration: 336.533931ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:48:21.604601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:48:21.102857Z","time spent":"501.728714ms","remote":"127.0.0.1:39376","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-02T17:48:21.638505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.150098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T17:48:21.638584Z","caller":"traceutil/trace.go:171","msg":"trace[713055487] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:963; }","duration":"511.259341ms","start":"2026-02-02T17:48:21.127293Z","end":"2026-02-02T17:48:21.638552Z","steps":["trace[713055487] 'agreement among raft nodes before linearized reading' (duration: 477.345125ms)","trace[713055487] 'count revisions from in-memory index tree' (duration: 33.809003ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:48:21.638627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:48:21.127278Z","time spent":"511.337793ms","remote":"127.0.0.1:39392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-02-02T17:48:21.639057Z","caller":"traceutil/trace.go:171","msg":"trace[990123173] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"108.353007ms","start":"2026-02-02T17:48:21.530689Z","end":"2026-02-02T17:48:21.639042Z","steps":["trace[990123173] 'process raft request' (duration: 104.176014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:48:21.639257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.348549ms","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-02T17:48:21.639286Z","caller":"traceutil/trace.go:171","msg":"trace[173178202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:964; }","duration":"442.402521ms","start":"2026-02-02T17:48:21.196874Z","end":"2026-02-02T17:48:21.639277Z","steps":["trace[173178202] 'agreement among raft nodes before linearized reading' (duration: 442.304948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:48:21.639311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:48:21.196851Z","time spent":"442.453933ms","remote":"127.0.0.1:39626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T17:48:21.644125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.532488ms","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-02T17:48:21.644152Z","caller":"traceutil/trace.go:171","msg":"trace[1474994833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:964; }","duration":"451.58975ms","start":"2026-02-02T17:48:21.192555Z","end":"2026-02-02T17:48:21.644145Z","steps":["trace[1474994833] 'agreement among raft nodes before linearized reading' (duration: 451.515828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:48:21.644176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:48:21.192517Z","time spent":"451.654791ms","remote":"127.0.0.1:39626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-02T17:48:21.644255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.011319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:48:21.644277Z","caller":"traceutil/trace.go:171","msg":"trace[570238339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"466.0694ms","start":"2026-02-02T17:48:21.178202Z","end":"2026-02-02T17:48:21.644272Z","steps":["trace[570238339] 'agreement among raft nodes before linearized reading' (duration: 466.039019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:48:21.644296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:48:21.17818Z","time spent":"466.113412ms","remote":"127.0.0.1:39328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:48:21.644413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.184869ms","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-02T17:48:21.644435Z","caller":"traceutil/trace.go:171","msg":"trace[891832135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:964; }","duration":"491.244661ms","start":"2026-02-02T17:48:21.153185Z","end":"2026-02-02T17:48:21.644429Z","steps":["trace[891832135] 'agreement among raft nodes before linearized reading' (duration: 491.19035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:48:21.644455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:48:21.153172Z","time spent":"491.279072ms","remote":"127.0.0.1:39626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-02T17:48:31.836521Z","caller":"traceutil/trace.go:171","msg":"trace[2080954171] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"105.364236ms","start":"2026-02-02T17:48:31.731134Z","end":"2026-02-02T17:48:31.836498Z","steps":["trace[2080954171] 'process raft request' (duration: 104.722699ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:51:37.034269Z","caller":"traceutil/trace.go:171","msg":"trace[748578962] linearizableReadLoop","detail":"{readStateIndex:1897; appliedIndex:1896; }","duration":"133.025989ms","start":"2026-02-02T17:51:36.901218Z","end":"2026-02-02T17:51:37.034244Z","steps":["trace[748578962] 'read index received' (duration: 43.533571ms)","trace[748578962] 'applied index is now lower than readState.Index' (duration: 89.491408ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:51:37.034746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.504052ms","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-02T17:51:37.034953Z","caller":"traceutil/trace.go:171","msg":"trace[888025525] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1820; }","duration":"133.723808ms","start":"2026-02-02T17:51:36.901212Z","end":"2026-02-02T17:51:37.034936Z","steps":["trace[888025525] 'agreement among raft nodes before linearized reading' (duration: 133.339987ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:51:37.034819Z","caller":"traceutil/trace.go:171","msg":"trace[122659891] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"183.927179ms","start":"2026-02-02T17:51:36.850872Z","end":"2026-02-02T17:51:37.034799Z","steps":["trace[122659891] 'process raft request' (duration: 93.785324ms)","trace[122659891] 'compare' (duration: 89.468977ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:52:06.604025Z","caller":"traceutil/trace.go:171","msg":"trace[747548870] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"188.664596ms","start":"2026-02-02T17:52:06.415322Z","end":"2026-02-02T17:52:06.603986Z","steps":["trace[747548870] 'process raft request' (duration: 188.485221ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:52:06.606204Z","caller":"traceutil/trace.go:171","msg":"trace[782376348] linearizableReadLoop","detail":"{readStateIndex:2030; appliedIndex:2030; }","duration":"177.964608ms","start":"2026-02-02T17:52:06.428208Z","end":"2026-02-02T17:52:06.606173Z","steps":["trace[782376348] 'read index received' (duration: 177.953358ms)","trace[782376348] 'applied index is now lower than readState.Index' (duration: 9.2µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:52:06.606995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.759219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:134 size:102427"} {"level":"info","ts":"2026-02-02T17:52:06.607036Z","caller":"traceutil/trace.go:171","msg":"trace[375169491] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:134; response_revision:1948; }","duration":"178.833151ms","start":"2026-02-02T17:52:06.42819Z","end":"2026-02-02T17:52:06.607023Z","steps":["trace[375169491] 'agreement among raft nodes before linearized reading' (duration: 178.118612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:52:06.609501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.193609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-02T17:52:06.609573Z","caller":"traceutil/trace.go:171","msg":"trace[178730562] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1948; }","duration":"173.295262ms","start":"2026-02-02T17:52:06.436267Z","end":"2026-02-02T17:52:06.609563Z","steps":["trace[178730562] 'agreement among raft nodes before linearized reading' (duration: 173.149058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:52:06.610146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.501604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:134 size:102427"} {"level":"info","ts":"2026-02-02T17:52:06.610206Z","caller":"traceutil/trace.go:171","msg":"trace[57488028] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:134; response_revision:1948; }","duration":"171.579326ms","start":"2026-02-02T17:52:06.438617Z","end":"2026-02-02T17:52:06.610197Z","steps":["trace[57488028] 'agreement among raft nodes before linearized reading' (duration: 171.086823ms)"],"step_count":1}