{"level":"warn","ts":"2026-01-30T00:29:52.382329Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-30T00:29:52.382557Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49: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.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49: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-01-30T00:29:52.382692Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-30T00:29:52.382759Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-01-30T00:29:52.382835Z","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-01-30T00:29:52.383592Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-01-30T00:29:52.384827Z","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.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49: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-01-30T00:29:52.430154Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"43.84804ms"} {"level":"info","ts":"2026-01-30T00:29:52.580867Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-01-30T00:29:52.581151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-01-30T00:29:52.58119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-01-30T00:29:52.581248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-30T00:29:52.581272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-01-30T00:29:52.581362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-01-30T00:29:52.69727Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-30T00:29:52.747375Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-30T00:29:52.830313Z","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-01-30T00:29:52.875656Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-30T00:29:52.885309Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-30T00:29:52.885483Z","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-01-30T00:29:52.885511Z","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-01-30T00:29:52.88552Z","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-01-30T00:29:52.888981Z","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-01-30T00:29:52.889118Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-01-30T00:29:52.889158Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-01-30T00:29:52.889276Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-30T00:29:52.889386Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-30T00:29:52.928318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-01-30T00:29:52.928512Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-01-30T00:29:53.682366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-01-30T00:29:53.682491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-01-30T00:29:53.682515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-01-30T00:29:53.682535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-01-30T00:29:53.682566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-01-30T00:29:53.682588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-01-30T00:29:53.682605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-01-30T00:29:53.722823Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-01-30T00:29:53.722953Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-30T00:29:53.723043Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-30T00:29:53.723357Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T00:29:53.723445Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-30T00:29:53.723465Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-30T00:29:53.728083Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-01-30T00:29:53.729329Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-30T00:29:53.755003Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T00:29:53.759605Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T00:29:53.75967Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T00:29:55.458585Z","caller":"traceutil/trace.go:171","msg":"trace[1817384760] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"129.087266ms","start":"2026-01-30T00:29:55.329476Z","end":"2026-01-30T00:29:55.458563Z","steps":["trace[1817384760] 'process raft request' (duration: 84.016161ms)","trace[1817384760] 'compare' (duration: 44.799897ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T00:29:55.458838Z","caller":"traceutil/trace.go:171","msg":"trace[753598413] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"124.615292ms","start":"2026-01-30T00:29:55.334213Z","end":"2026-01-30T00:29:55.458828Z","steps":["trace[753598413] 'process raft request' (duration: 124.165219ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:29:55.458946Z","caller":"traceutil/trace.go:171","msg":"trace[589248942] linearizableReadLoop","detail":"{readStateIndex:25; appliedIndex:23; }","duration":"131.855002ms","start":"2026-01-30T00:29:55.327082Z","end":"2026-01-30T00:29:55.458937Z","steps":["trace[589248942] 'read index received' (duration: 52.324765ms)","trace[589248942] 'applied index is now lower than readState.Index' (duration: 79.529277ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T00:29:55.459132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.359923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-30T00:29:55.459187Z","caller":"traceutil/trace.go:171","msg":"trace[97155457] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:32; }","duration":"129.421564ms","start":"2026-01-30T00:29:55.329755Z","end":"2026-01-30T00:29:55.459176Z","steps":["trace[97155457] 'agreement among raft nodes before linearized reading' (duration: 129.346212ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:29:55.459121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.019406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-01-30T00:29:55.459226Z","caller":"traceutil/trace.go:171","msg":"trace[1944913183] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:32; }","duration":"132.14927ms","start":"2026-01-30T00:29:55.327064Z","end":"2026-01-30T00:29:55.459214Z","steps":["trace[1944913183] 'agreement among raft nodes before linearized reading' (duration: 131.975215ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:29:56.293257Z","caller":"traceutil/trace.go:171","msg":"trace[1629549487] transaction","detail":"{read_only:false; response_revision:86; number_of_response:1; }","duration":"144.410509ms","start":"2026-01-30T00:29:56.148705Z","end":"2026-01-30T00:29:56.293115Z","steps":["trace[1629549487] 'process raft request' (duration: 142.850155ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:30:11.906407Z","caller":"traceutil/trace.go:171","msg":"trace[701924485] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"227.768301ms","start":"2026-01-30T00:30:11.678621Z","end":"2026-01-30T00:30:11.90639Z","steps":["trace[701924485] 'read index received' (duration: 227.617447ms)","trace[701924485] 'applied index is now lower than readState.Index' (duration: 150.094µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T00:30:11.906587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.950537ms","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-01-30T00:30:11.906665Z","caller":"traceutil/trace.go:171","msg":"trace[307761026] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:406; }","duration":"228.049589ms","start":"2026-01-30T00:30:11.678584Z","end":"2026-01-30T00:30:11.906634Z","steps":["trace[307761026] 'agreement among raft nodes before linearized reading' (duration: 227.907435ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:30:11.906701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.534948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6894"} {"level":"info","ts":"2026-01-30T00:30:11.906733Z","caller":"traceutil/trace.go:171","msg":"trace[1554182313] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:406; }","duration":"129.60639ms","start":"2026-01-30T00:30:11.777117Z","end":"2026-01-30T00:30:11.906724Z","steps":["trace[1554182313] 'agreement among raft nodes before linearized reading' (duration: 129.505028ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:30:11.906844Z","caller":"traceutil/trace.go:171","msg":"trace[568209624] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"244.503483ms","start":"2026-01-30T00:30:11.66233Z","end":"2026-01-30T00:30:11.906834Z","steps":["trace[568209624] 'process raft request' (duration: 243.957658ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:30:12.070701Z","caller":"traceutil/trace.go:171","msg":"trace[1404727640] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"153.599252ms","start":"2026-01-30T00:30:11.917086Z","end":"2026-01-30T00:30:12.070685Z","steps":["trace[1404727640] 'process raft request' (duration: 153.576201ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:30:12.070992Z","caller":"traceutil/trace.go:171","msg":"trace[1660308865] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"158.841957ms","start":"2026-01-30T00:30:11.912145Z","end":"2026-01-30T00:30:12.070987Z","steps":["trace[1660308865] 'process raft request' (duration: 153.917051ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:30:12.071112Z","caller":"traceutil/trace.go:171","msg":"trace[149692228] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"156.320788ms","start":"2026-01-30T00:30:11.914787Z","end":"2026-01-30T00:30:12.071108Z","steps":["trace[149692228] 'process raft request' (duration: 155.821954ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:30:12.071162Z","caller":"traceutil/trace.go:171","msg":"trace[926803568] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"154.129297ms","start":"2026-01-30T00:30:11.917029Z","end":"2026-01-30T00:30:12.071159Z","steps":["trace[926803568] 'process raft request' (duration: 153.613603ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:30:26.12279Z","caller":"traceutil/trace.go:171","msg":"trace[1460372076] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"103.136088ms","start":"2026-01-30T00:30:26.019639Z","end":"2026-01-30T00:30:26.122775Z","steps":["trace[1460372076] 'process raft request' (duration: 102.912902ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:30:26.260163Z","caller":"traceutil/trace.go:171","msg":"trace[406312868] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"134.817533ms","start":"2026-01-30T00:30:26.125329Z","end":"2026-01-30T00:30:26.260147Z","steps":["trace[406312868] 'process raft request' (duration: 116.094936ms)","trace[406312868] 'compare' (duration: 18.373398ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T00:30:30.745023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.003697ms","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-01-30T00:30:30.745104Z","caller":"traceutil/trace.go:171","msg":"trace[458395282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:487; }","duration":"413.12236ms","start":"2026-01-30T00:30:30.331961Z","end":"2026-01-30T00:30:30.745084Z","steps":["trace[458395282] 'range keys from in-memory index tree' (duration: 412.849292ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:30:30.745146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:30:30.331947Z","time spent":"413.190092ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-30T00:30:30.745429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.86746ms","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-01-30T00:30:30.745457Z","caller":"traceutil/trace.go:171","msg":"trace[2001949085] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:487; }","duration":"405.923662ms","start":"2026-01-30T00:30:30.339524Z","end":"2026-01-30T00:30:30.745448Z","steps":["trace[2001949085] 'range keys from in-memory index tree' (duration: 405.786648ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:30:30.745482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:30:30.339512Z","time spent":"405.964833ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-30T00:30:30.745621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.492021ms","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-01-30T00:30:30.745645Z","caller":"traceutil/trace.go:171","msg":"trace[607735564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:487; }","duration":"404.523463ms","start":"2026-01-30T00:30:30.341114Z","end":"2026-01-30T00:30:30.745638Z","steps":["trace[607735564] 'range keys from in-memory index tree' (duration: 404.401189ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:30:30.745668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:30:30.341109Z","time spent":"404.554224ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-30T00:30:30.745868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.313759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T00:30:30.745896Z","caller":"traceutil/trace.go:171","msg":"trace[894598217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:487; }","duration":"235.376371ms","start":"2026-01-30T00:30:30.510511Z","end":"2026-01-30T00:30:30.745888Z","steps":["trace[894598217] 'range keys from in-memory index tree' (duration: 235.252807ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:30:30.920562Z","caller":"traceutil/trace.go:171","msg":"trace[1558962108] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"167.073185ms","start":"2026-01-30T00:30:30.753471Z","end":"2026-01-30T00:30:30.920544Z","steps":["trace[1558962108] 'process raft request' (duration: 166.864179ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:30:31.179389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.492803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T00:30:31.179553Z","caller":"traceutil/trace.go:171","msg":"trace[2095663784] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:506; }","duration":"284.383685ms","start":"2026-01-30T00:30:30.895154Z","end":"2026-01-30T00:30:31.179537Z","steps":["trace[2095663784] 'read index received' (duration: 25.724131ms)","trace[2095663784] 'applied index is now lower than readState.Index' (duration: 258.658584ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T00:30:31.17966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.501378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bkbbr\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-01-30T00:30:31.179686Z","caller":"traceutil/trace.go:171","msg":"trace[1166407339] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bkbbr; range_end:; response_count:1; response_revision:490; }","duration":"284.56719ms","start":"2026-01-30T00:30:30.89511Z","end":"2026-01-30T00:30:31.179677Z","steps":["trace[1166407339] 'agreement among raft nodes before linearized reading' (duration: 284.471477ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:30:31.180002Z","caller":"traceutil/trace.go:171","msg":"trace[730671759] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"422.8907ms","start":"2026-01-30T00:30:30.757098Z","end":"2026-01-30T00:30:31.179988Z","steps":["trace[730671759] 'process raft request' (duration: 299.243725ms)","trace[730671759] 'compare' (duration: 122.348889ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T00:30:31.180066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:30:30.757089Z","time spent":"422.942821ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T00:30:31.180123Z","caller":"traceutil/trace.go:171","msg":"trace[296157002] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"421.275715ms","start":"2026-01-30T00:30:30.75879Z","end":"2026-01-30T00:30:31.180066Z","steps":["trace[296157002] 'process raft request' (duration: 420.688729ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:30:31.180277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:30:30.758782Z","time spent":"421.406429ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T00:30:31.326667Z","caller":"traceutil/trace.go:171","msg":"trace[1208061520] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"132.818648ms","start":"2026-01-30T00:30:31.193816Z","end":"2026-01-30T00:30:31.326635Z","steps":["trace[1208061520] 'process raft request' (duration: 132.683624ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:30:33.331104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.247612ms","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-01-30T00:30:33.33117Z","caller":"traceutil/trace.go:171","msg":"trace[1477744409] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:504; }","duration":"141.366965ms","start":"2026-01-30T00:30:33.189787Z","end":"2026-01-30T00:30:33.331154Z","steps":["trace[1477744409] 'range keys from in-memory index tree' (duration: 141.108388ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:30:33.331369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.685062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-01-30T00:30:33.331442Z","caller":"traceutil/trace.go:171","msg":"trace[1174158357] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:504; }","duration":"213.757704ms","start":"2026-01-30T00:30:33.117678Z","end":"2026-01-30T00:30:33.331435Z","steps":["trace[1174158357] 'range keys from in-memory index tree' (duration: 213.595279ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:30:33.331543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.643542ms","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-01-30T00:30:33.331564Z","caller":"traceutil/trace.go:171","msg":"trace[2131957700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:504; }","duration":"141.674563ms","start":"2026-01-30T00:30:33.189885Z","end":"2026-01-30T00:30:33.331559Z","steps":["trace[2131957700] 'range keys from in-memory index tree' (duration: 141.609361ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:30:45.716294Z","caller":"traceutil/trace.go:171","msg":"trace[1986965675] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"215.347158ms","start":"2026-01-30T00:30:45.50093Z","end":"2026-01-30T00:30:45.716277Z","steps":["trace[1986965675] 'process raft request' (duration: 215.138492ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:30:45.719035Z","caller":"traceutil/trace.go:171","msg":"trace[1389795667] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:677; }","duration":"186.4531ms","start":"2026-01-30T00:30:45.53257Z","end":"2026-01-30T00:30:45.719023Z","steps":["trace[1389795667] 'read index received' (duration: 183.54816ms)","trace[1389795667] 'applied index is now lower than readState.Index' (duration: 2.90421ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T00:30:45.719219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.643084ms","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-01-30T00:30:45.719249Z","caller":"traceutil/trace.go:171","msg":"trace[1566469602] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:657; }","duration":"186.721227ms","start":"2026-01-30T00:30:45.53252Z","end":"2026-01-30T00:30:45.719241Z","steps":["trace[1566469602] 'agreement among raft nodes before linearized reading' (duration: 186.550452ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:30:45.719399Z","caller":"traceutil/trace.go:171","msg":"trace[740857612] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"217.023534ms","start":"2026-01-30T00:30:45.50237Z","end":"2026-01-30T00:30:45.719394Z","steps":["trace[740857612] 'process raft request' (duration: 216.597032ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:30:47.948549Z","caller":"traceutil/trace.go:171","msg":"trace[503555427] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"224.413328ms","start":"2026-01-30T00:30:47.72412Z","end":"2026-01-30T00:30:47.948533Z","steps":["trace[503555427] 'process raft request' (duration: 224.316795ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:30:47.948879Z","caller":"traceutil/trace.go:171","msg":"trace[1423359249] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:687; }","duration":"223.73619ms","start":"2026-01-30T00:30:47.725124Z","end":"2026-01-30T00:30:47.948861Z","steps":["trace[1423359249] 'read index received' (duration: 223.722859ms)","trace[1423359249] 'applied index is now lower than readState.Index' (duration: 13.011µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T00:30:47.94897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.609178ms","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-01-30T00:30:47.948985Z","caller":"traceutil/trace.go:171","msg":"trace[2016117872] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:664; }","duration":"222.646069ms","start":"2026-01-30T00:30:47.726334Z","end":"2026-01-30T00:30:47.94898Z","steps":["trace[2016117872] 'agreement among raft nodes before linearized reading' (duration: 222.572507ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:30:47.949089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.963566ms","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-01-30T00:30:47.949102Z","caller":"traceutil/trace.go:171","msg":"trace[1750804170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:664; }","duration":"223.975966ms","start":"2026-01-30T00:30:47.725122Z","end":"2026-01-30T00:30:47.949098Z","steps":["trace[1750804170] 'agreement among raft nodes before linearized reading' (duration: 223.936595ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:30:48.502914Z","caller":"traceutil/trace.go:171","msg":"trace[1109115416] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"545.824444ms","start":"2026-01-30T00:30:47.95705Z","end":"2026-01-30T00:30:48.502875Z","steps":["trace[1109115416] 'process raft request' (duration: 545.704381ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:30:48.503666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:30:47.957034Z","time spent":"545.945267ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T00:30:48.508269Z","caller":"traceutil/trace.go:171","msg":"trace[309456705] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:687; }","duration":"155.419963ms","start":"2026-01-30T00:30:48.352841Z","end":"2026-01-30T00:30:48.508261Z","steps":["trace[309456705] 'read index received' (duration: 150.017254ms)","trace[309456705] 'applied index is now lower than readState.Index' (duration: 5.402079ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T00:30:48.508318Z","caller":"traceutil/trace.go:171","msg":"trace[743921927] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"551.080229ms","start":"2026-01-30T00:30:47.957215Z","end":"2026-01-30T00:30:48.508295Z","steps":["trace[743921927] 'process raft request' (duration: 550.976276ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:30:48.508358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.514535ms","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-01-30T00:30:48.508376Z","caller":"traceutil/trace.go:171","msg":"trace[899566255] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:667; }","duration":"155.564627ms","start":"2026-01-30T00:30:48.352806Z","end":"2026-01-30T00:30:48.508371Z","steps":["trace[899566255] 'agreement among raft nodes before linearized reading' (duration: 155.484075ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:30:48.508426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:30:47.957208Z","time spent":"551.156852ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T00:30:52.302857Z","caller":"traceutil/trace.go:171","msg":"trace[1691021388] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"335.396024ms","start":"2026-01-30T00:30:51.967436Z","end":"2026-01-30T00:30:52.302832Z","steps":["trace[1691021388] 'process raft request' (duration: 335.25388ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:30:52.303019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:30:51.96741Z","time spent":"335.521337ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T00:31:02.831174Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276498892868360,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-30T00:31:03.332232Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276498892868360,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-30T00:31:03.52013Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.213474551s","expected-duration":"1s"} {"level":"info","ts":"2026-01-30T00:31:03.521631Z","caller":"traceutil/trace.go:171","msg":"trace[756143719] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:734; }","duration":"1.190819389s","start":"2026-01-30T00:31:02.330773Z","end":"2026-01-30T00:31:03.521593Z","steps":["trace[756143719] 'read index received' (duration: 1.189485032s)","trace[756143719] 'applied index is now lower than readState.Index' (duration: 1.330667ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T00:31:03.521778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190979443s","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-01-30T00:31:03.521826Z","caller":"traceutil/trace.go:171","msg":"trace[1763376809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:709; }","duration":"1.191069135s","start":"2026-01-30T00:31:02.330746Z","end":"2026-01-30T00:31:03.521815Z","steps":["trace[1763376809] 'agreement among raft nodes before linearized reading' (duration: 1.19090742s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:03.52186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:02.330733Z","time spent":"1.191122277s","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-30T00:31:03.521861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139147525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T00:31:03.521929Z","caller":"traceutil/trace.go:171","msg":"trace[511402223] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:709; }","duration":"1.139255079s","start":"2026-01-30T00:31:02.38266Z","end":"2026-01-30T00:31:03.521915Z","steps":["trace[511402223] 'agreement among raft nodes before linearized reading' (duration: 1.139129935s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:03.521981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:02.382647Z","time spent":"1.13931377s","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-01-30T00:31:03.522633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106908105s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T00:31:03.522655Z","caller":"traceutil/trace.go:171","msg":"trace[1594106774] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:709; }","duration":"1.106941496s","start":"2026-01-30T00:31:02.415706Z","end":"2026-01-30T00:31:03.522648Z","steps":["trace[1594106774] 'agreement among raft nodes before linearized reading' (duration: 1.106893955s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:03.522681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:02.415681Z","time spent":"1.106995148s","remote":"127.0.0.1:40200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-01-30T00:31:03.522905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107212724s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T00:31:03.522954Z","caller":"traceutil/trace.go:171","msg":"trace[1975956693] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:709; }","duration":"1.107260115s","start":"2026-01-30T00:31:02.415672Z","end":"2026-01-30T00:31:03.522932Z","steps":["trace[1975956693] 'agreement among raft nodes before linearized reading' (duration: 1.107145502s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:03.522987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:02.41567Z","time spent":"1.107308206s","remote":"127.0.0.1:40200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-01-30T00:31:03.52384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.633077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T00:31:03.523878Z","caller":"traceutil/trace.go:171","msg":"trace[292204286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:709; }","duration":"183.701999ms","start":"2026-01-30T00:31:03.340164Z","end":"2026-01-30T00:31:03.523866Z","steps":["trace[292204286] 'agreement among raft nodes before linearized reading' (duration: 183.637107ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:03.524236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.576367ms","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-01-30T00:31:03.524255Z","caller":"traceutil/trace.go:171","msg":"trace[1893229852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:709; }","duration":"471.608698ms","start":"2026-01-30T00:31:03.052637Z","end":"2026-01-30T00:31:03.524246Z","steps":["trace[1893229852] 'agreement among raft nodes before linearized reading' (duration: 471.505105ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:03.524275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:03.052627Z","time spent":"471.64469ms","remote":"127.0.0.1:52052","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-01-30T00:31:03.524676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.016171ms","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-01-30T00:31:03.524695Z","caller":"traceutil/trace.go:171","msg":"trace[2129046693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:709; }","duration":"890.050071ms","start":"2026-01-30T00:31:02.634637Z","end":"2026-01-30T00:31:03.524687Z","steps":["trace[2129046693] 'agreement among raft nodes before linearized reading' (duration: 889.946638ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:03.524716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:02.634625Z","time spent":"890.087652ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-30T00:31:03.52496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.568514ms","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-01-30T00:31:03.52498Z","caller":"traceutil/trace.go:171","msg":"trace[626864290] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:709; }","duration":"958.588625ms","start":"2026-01-30T00:31:02.566382Z","end":"2026-01-30T00:31:03.524971Z","steps":["trace[626864290] 'agreement among raft nodes before linearized reading' (duration: 958.492632ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:03.524993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:02.566366Z","time spent":"958.624316ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-30T00:31:03.525289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.747037ms","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-01-30T00:31:03.525327Z","caller":"traceutil/trace.go:171","msg":"trace[1042148104] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:709; }","duration":"964.797149ms","start":"2026-01-30T00:31:02.560517Z","end":"2026-01-30T00:31:03.525314Z","steps":["trace[1042148104] 'agreement among raft nodes before linearized reading' (duration: 964.643544ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:03.525347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:02.560505Z","time spent":"964.836279ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-30T00:31:03.526352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14362064s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T00:31:03.526375Z","caller":"traceutil/trace.go:171","msg":"trace[1960930446] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:709; }","duration":"1.143648911s","start":"2026-01-30T00:31:02.382716Z","end":"2026-01-30T00:31:03.526365Z","steps":["trace[1960930446] 'agreement among raft nodes before linearized reading' (duration: 1.14360618s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:03.526397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:02.382713Z","time spent":"1.143673592s","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-01-30T00:31:03.526518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124364783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T00:31:03.526547Z","caller":"traceutil/trace.go:171","msg":"trace[732732845] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:709; }","duration":"1.124389733s","start":"2026-01-30T00:31:02.402139Z","end":"2026-01-30T00:31:03.526529Z","steps":["trace[732732845] 'agreement among raft nodes before linearized reading' (duration: 1.124358583s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:03.526562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:02.402136Z","time spent":"1.124421304s","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-01-30T00:31:03.527035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124897337s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T00:31:03.527055Z","caller":"traceutil/trace.go:171","msg":"trace[1216522346] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:709; }","duration":"1.124928298s","start":"2026-01-30T00:31:02.402122Z","end":"2026-01-30T00:31:03.52705Z","steps":["trace[1216522346] 'agreement among raft nodes before linearized reading' (duration: 1.124898637s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:03.527069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:02.402118Z","time spent":"1.124946679s","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-01-30T00:31:03.527145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125038872s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T00:31:03.527164Z","caller":"traceutil/trace.go:171","msg":"trace[1481874873] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:709; }","duration":"1.125058142s","start":"2026-01-30T00:31:02.4021Z","end":"2026-01-30T00:31:03.527159Z","steps":["trace[1481874873] 'agreement among raft nodes before linearized reading' (duration: 1.125031261s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:03.52718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:02.402072Z","time spent":"1.125102433s","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-01-30T00:31:03.527515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144799543s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T00:31:03.527532Z","caller":"traceutil/trace.go:171","msg":"trace[1307425924] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:709; }","duration":"1.144830034s","start":"2026-01-30T00:31:02.382697Z","end":"2026-01-30T00:31:03.527527Z","steps":["trace[1307425924] 'agreement among raft nodes before linearized reading' (duration: 1.144799273s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:03.52755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:02.382694Z","time spent":"1.144851985s","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-01-30T00:31:03.523727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108054897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T00:31:03.535197Z","caller":"traceutil/trace.go:171","msg":"trace[743056208] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:709; }","duration":"1.119535288s","start":"2026-01-30T00:31:02.415649Z","end":"2026-01-30T00:31:03.535184Z","steps":["trace[743056208] 'agreement among raft nodes before linearized reading' (duration: 1.107815531s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:03.53523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:02.415642Z","time spent":"1.119576549s","remote":"127.0.0.1:40200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"info","ts":"2026-01-30T00:31:13.866435Z","caller":"traceutil/trace.go:171","msg":"trace[1427142162] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"181.879628ms","start":"2026-01-30T00:31:13.684531Z","end":"2026-01-30T00:31:13.866411Z","steps":["trace[1427142162] 'process raft request' (duration: 159.850593ms)","trace[1427142162] 'compare' (duration: 21.635294ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T00:31:13.932489Z","caller":"traceutil/trace.go:171","msg":"trace[831318552] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"246.772181ms","start":"2026-01-30T00:31:13.685691Z","end":"2026-01-30T00:31:13.932463Z","steps":["trace[831318552] 'process raft request' (duration: 246.727429ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:31:13.932628Z","caller":"traceutil/trace.go:171","msg":"trace[684638384] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"247.922512ms","start":"2026-01-30T00:31:13.684671Z","end":"2026-01-30T00:31:13.932594Z","steps":["trace[684638384] 'process raft request' (duration: 247.610464ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:31:13.932764Z","caller":"traceutil/trace.go:171","msg":"trace[756848299] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"247.136991ms","start":"2026-01-30T00:31:13.685615Z","end":"2026-01-30T00:31:13.932752Z","steps":["trace[756848299] 'process raft request' (duration: 246.76794ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:31:22.282363Z","caller":"traceutil/trace.go:171","msg":"trace[1509005249] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"100.829285ms","start":"2026-01-30T00:31:22.181513Z","end":"2026-01-30T00:31:22.282342Z","steps":["trace[1509005249] 'process raft request' (duration: 57.484445ms)","trace[1509005249] 'compare' (duration: 42.804905ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T00:31:27.857189Z","caller":"traceutil/trace.go:171","msg":"trace[704459116] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"102.396749ms","start":"2026-01-30T00:31:27.754769Z","end":"2026-01-30T00:31:27.857166Z","steps":["trace[704459116] 'process raft request' (duration: 102.086291ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:31:30.021231Z","caller":"traceutil/trace.go:171","msg":"trace[978147394] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"157.859368ms","start":"2026-01-30T00:31:29.863355Z","end":"2026-01-30T00:31:30.021215Z","steps":["trace[978147394] 'process raft request' (duration: 157.435766ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:30.528983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276498892868672,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-30T00:31:30.791926Z","caller":"traceutil/trace.go:171","msg":"trace[569480082] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"766.929304ms","start":"2026-01-30T00:31:30.024961Z","end":"2026-01-30T00:31:30.791891Z","steps":["trace[569480082] 'process raft request' (duration: 765.723451ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:30.792087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:30.024936Z","time spent":"767.052528ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T00:31:30.792593Z","caller":"traceutil/trace.go:171","msg":"trace[1769397537] linearizableReadLoop","detail":"{readStateIndex:869; appliedIndex:867; }","duration":"764.440135ms","start":"2026-01-30T00:31:30.028141Z","end":"2026-01-30T00:31:30.792582Z","steps":["trace[1769397537] 'read index received' (duration: 762.554232ms)","trace[1769397537] 'applied index is now lower than readState.Index' (duration: 1.885113ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T00:31:30.792881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.712162ms","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-01-30T00:31:30.792934Z","caller":"traceutil/trace.go:171","msg":"trace[1452939473] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:836; }","duration":"764.794595ms","start":"2026-01-30T00:31:30.028127Z","end":"2026-01-30T00:31:30.792921Z","steps":["trace[1452939473] 'agreement among raft nodes before linearized reading' (duration: 764.590059ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:30.792961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:30.028122Z","time spent":"764.834826ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-30T00:31:30.792955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.414513ms","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-01-30T00:31:30.79301Z","caller":"traceutil/trace.go:171","msg":"trace[1120052093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:836; }","duration":"760.479284ms","start":"2026-01-30T00:31:30.032517Z","end":"2026-01-30T00:31:30.792996Z","steps":["trace[1120052093] 'agreement among raft nodes before linearized reading' (duration: 760.34061ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:30.793044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:30.032512Z","time spent":"760.524825ms","remote":"127.0.0.1:52052","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-01-30T00:31:30.793141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.867487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-30T00:31:30.793165Z","caller":"traceutil/trace.go:171","msg":"trace[1636691709] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:836; }","duration":"114.922729ms","start":"2026-01-30T00:31:30.678234Z","end":"2026-01-30T00:31:30.793157Z","steps":["trace[1636691709] 'agreement among raft nodes before linearized reading' (duration: 114.882208ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:31:30.793228Z","caller":"traceutil/trace.go:171","msg":"trace[210537960] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"767.327946ms","start":"2026-01-30T00:31:30.025893Z","end":"2026-01-30T00:31:30.793221Z","steps":["trace[210537960] 'process raft request' (duration: 766.640956ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:30.793287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:30.025888Z","time spent":"767.357326ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T00:31:30.793332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.150994ms","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-01-30T00:31:30.793364Z","caller":"traceutil/trace.go:171","msg":"trace[606295597] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:836; }","duration":"207.220066ms","start":"2026-01-30T00:31:30.586134Z","end":"2026-01-30T00:31:30.793354Z","steps":["trace[606295597] 'agreement among raft nodes before linearized reading' (duration: 207.161145ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:30.796788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.806487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T00:31:30.796813Z","caller":"traceutil/trace.go:171","msg":"trace[2025194043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:836; }","duration":"282.859998ms","start":"2026-01-30T00:31:30.513946Z","end":"2026-01-30T00:31:30.796806Z","steps":["trace[2025194043] 'agreement among raft nodes before linearized reading' (duration: 282.816136ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:31:33.001998Z","caller":"traceutil/trace.go:171","msg":"trace[768138933] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"161.204531ms","start":"2026-01-30T00:31:32.840775Z","end":"2026-01-30T00:31:33.00198Z","steps":["trace[768138933] 'process raft request' (duration: 97.704618ms)","trace[768138933] 'compare' (duration: 63.424911ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T00:31:33.002263Z","caller":"traceutil/trace.go:171","msg":"trace[492782627] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"159.009159ms","start":"2026-01-30T00:31:32.843248Z","end":"2026-01-30T00:31:33.002258Z","steps":["trace[492782627] 'process raft request' (duration: 158.992499ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:31:33.002364Z","caller":"traceutil/trace.go:171","msg":"trace[127792735] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"160.304276ms","start":"2026-01-30T00:31:32.842054Z","end":"2026-01-30T00:31:33.002359Z","steps":["trace[127792735] 'process raft request' (duration: 160.152452ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:31:38.323549Z","caller":"traceutil/trace.go:171","msg":"trace[40105632] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"280.516541ms","start":"2026-01-30T00:31:38.04301Z","end":"2026-01-30T00:31:38.323527Z","steps":["trace[40105632] 'process raft request' (duration: 280.401558ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:31:40.844375Z","caller":"traceutil/trace.go:171","msg":"trace[132223988] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"508.279571ms","start":"2026-01-30T00:31:40.33608Z","end":"2026-01-30T00:31:40.844359Z","steps":["trace[132223988] 'process raft request' (duration: 508.174388ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:40.844515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:40.336071Z","time spent":"508.385544ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T00:31:45.139174Z","caller":"traceutil/trace.go:171","msg":"trace[1911166996] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"189.520991ms","start":"2026-01-30T00:31:44.949637Z","end":"2026-01-30T00:31:45.139158Z","steps":["trace[1911166996] 'process raft request' (duration: 129.563397ms)","trace[1911166996] 'compare' (duration: 59.676706ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T00:31:45.32993Z","caller":"traceutil/trace.go:171","msg":"trace[1237876944] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:910; }","duration":"187.133146ms","start":"2026-01-30T00:31:45.14278Z","end":"2026-01-30T00:31:45.329913Z","steps":["trace[1237876944] 'read index received' (duration: 111.044421ms)","trace[1237876944] 'applied index is now lower than readState.Index' (duration: 76.088034ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T00:31:45.330004Z","caller":"traceutil/trace.go:171","msg":"trace[322108947] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"187.868836ms","start":"2026-01-30T00:31:45.142129Z","end":"2026-01-30T00:31:45.329998Z","steps":["trace[322108947] 'process raft request' (duration: 111.687509ms)","trace[322108947] 'compare' (duration: 75.981151ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T00:31:45.330047Z","caller":"traceutil/trace.go:171","msg":"trace[820373675] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"187.196717ms","start":"2026-01-30T00:31:45.142832Z","end":"2026-01-30T00:31:45.330028Z","steps":["trace[820373675] 'process raft request' (duration: 187.054553ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:45.330221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.428264ms","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-01-30T00:31:45.330239Z","caller":"traceutil/trace.go:171","msg":"trace[1904181212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:876; }","duration":"187.472284ms","start":"2026-01-30T00:31:45.142761Z","end":"2026-01-30T00:31:45.330234Z","steps":["trace[1904181212] 'agreement among raft nodes before linearized reading' (duration: 187.220387ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:31:47.502391Z","caller":"traceutil/trace.go:171","msg":"trace[1403599102] linearizableReadLoop","detail":"{readStateIndex:914; appliedIndex:913; }","duration":"411.806938ms","start":"2026-01-30T00:31:47.090567Z","end":"2026-01-30T00:31:47.502374Z","steps":["trace[1403599102] 'read index received' (duration: 411.674074ms)","trace[1403599102] 'applied index is now lower than readState.Index' (duration: 131.954µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T00:31:47.502735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.116074ms","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-01-30T00:31:47.502762Z","caller":"traceutil/trace.go:171","msg":"trace[741018953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:878; }","duration":"360.179826ms","start":"2026-01-30T00:31:47.142574Z","end":"2026-01-30T00:31:47.502754Z","steps":["trace[741018953] 'agreement among raft nodes before linearized reading' (duration: 360.067913ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:47.502799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:47.142564Z","time spent":"360.229937ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-30T00:31:47.502944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.290914ms","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-01-30T00:31:47.502962Z","caller":"traceutil/trace.go:171","msg":"trace[1374439063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:878; }","duration":"146.321025ms","start":"2026-01-30T00:31:47.356635Z","end":"2026-01-30T00:31:47.502956Z","steps":["trace[1374439063] 'agreement among raft nodes before linearized reading' (duration: 146.267903ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:47.503065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.33627ms","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-01-30T00:31:47.503081Z","caller":"traceutil/trace.go:171","msg":"trace[467410295] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:878; }","duration":"168.36203ms","start":"2026-01-30T00:31:47.334714Z","end":"2026-01-30T00:31:47.503076Z","steps":["trace[467410295] 'agreement among raft nodes before linearized reading' (duration: 168.315039ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:47.503177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.40577ms","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-01-30T00:31:47.503194Z","caller":"traceutil/trace.go:171","msg":"trace[814001389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:878; }","duration":"169.4467ms","start":"2026-01-30T00:31:47.333743Z","end":"2026-01-30T00:31:47.503189Z","steps":["trace[814001389] 'agreement among raft nodes before linearized reading' (duration: 169.399879ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:31:47.503442Z","caller":"traceutil/trace.go:171","msg":"trace[1914896539] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"585.710614ms","start":"2026-01-30T00:31:46.917724Z","end":"2026-01-30T00:31:47.503434Z","steps":["trace[1914896539] 'process raft request' (duration: 584.527851ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:47.503497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:46.917703Z","time spent":"585.758735ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T00:31:47.510257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.684138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-30T00:31:47.510326Z","caller":"traceutil/trace.go:171","msg":"trace[1910438180] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:878; }","duration":"419.775751ms","start":"2026-01-30T00:31:47.090541Z","end":"2026-01-30T00:31:47.510317Z","steps":["trace[1910438180] 'agreement among raft nodes before linearized reading' (duration: 411.957832ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:31:47.510425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:47.090532Z","time spent":"419.884733ms","remote":"127.0.0.1:51802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"info","ts":"2026-01-30T00:31:47.68747Z","caller":"traceutil/trace.go:171","msg":"trace[1751802837] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:914; }","duration":"173.635809ms","start":"2026-01-30T00:31:47.513819Z","end":"2026-01-30T00:31:47.687455Z","steps":["trace[1751802837] 'read index received' (duration: 83.344448ms)","trace[1751802837] 'applied index is now lower than readState.Index' (duration: 90.290631ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T00:31:47.687562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.729121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T00:31:47.687609Z","caller":"traceutil/trace.go:171","msg":"trace[244875428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"173.760782ms","start":"2026-01-30T00:31:47.513815Z","end":"2026-01-30T00:31:47.687576Z","steps":["trace[244875428] 'agreement among raft nodes before linearized reading' (duration: 173.69971ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:31:47.687884Z","caller":"traceutil/trace.go:171","msg":"trace[134569455] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"174.694057ms","start":"2026-01-30T00:31:47.513182Z","end":"2026-01-30T00:31:47.687876Z","steps":["trace[134569455] 'process raft request' (duration: 174.115481ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:31:47.687979Z","caller":"traceutil/trace.go:171","msg":"trace[1497979276] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"173.167605ms","start":"2026-01-30T00:31:47.514806Z","end":"2026-01-30T00:31:47.687974Z","steps":["trace[1497979276] 'process raft request' (duration: 172.562528ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:31:47.688048Z","caller":"traceutil/trace.go:171","msg":"trace[1995349574] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"171.371695ms","start":"2026-01-30T00:31:47.516671Z","end":"2026-01-30T00:31:47.688043Z","steps":["trace[1995349574] 'process raft request' (duration: 170.720137ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:31:47.68813Z","caller":"traceutil/trace.go:171","msg":"trace[1677926377] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"171.419176ms","start":"2026-01-30T00:31:47.516705Z","end":"2026-01-30T00:31:47.688124Z","steps":["trace[1677926377] 'process raft request' (duration: 170.709386ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:31:47.688199Z","caller":"traceutil/trace.go:171","msg":"trace[207191262] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"138.221349ms","start":"2026-01-30T00:31:47.549973Z","end":"2026-01-30T00:31:47.688194Z","steps":["trace[207191262] 'process raft request' (duration: 137.461438ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:31:59.836676Z","caller":"traceutil/trace.go:171","msg":"trace[852926073] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"204.328204ms","start":"2026-01-30T00:31:59.632332Z","end":"2026-01-30T00:31:59.836661Z","steps":["trace[852926073] 'process raft request' (duration: 204.130999ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:32:00.280605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.746984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T00:32:00.280799Z","caller":"traceutil/trace.go:171","msg":"trace[1492483063] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"439.618035ms","start":"2026-01-30T00:31:59.841161Z","end":"2026-01-30T00:32:00.280779Z","steps":["trace[1492483063] 'process raft request' (duration: 18.440765ms)","trace[1492483063] 'compare' (duration: 263.571479ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T00:32:00.280882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:59.841149Z","time spent":"439.688196ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T00:32:00.325804Z","caller":"traceutil/trace.go:171","msg":"trace[405853135] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"477.992296ms","start":"2026-01-30T00:31:59.847797Z","end":"2026-01-30T00:32:00.325789Z","steps":["trace[405853135] 'process raft request' (duration: 477.966775ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:32:00.32589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:59.84778Z","time spent":"478.065578ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T00:32:00.32612Z","caller":"traceutil/trace.go:171","msg":"trace[236275424] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"483.643124ms","start":"2026-01-30T00:31:59.842471Z","end":"2026-01-30T00:32:00.326114Z","steps":["trace[236275424] 'process raft request' (duration: 438.273607ms)","trace[236275424] 'compare' (duration: 44.917304ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T00:32:00.32615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:59.842465Z","time spent":"483.667714ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T00:32:00.326208Z","caller":"traceutil/trace.go:171","msg":"trace[1138145573] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"482.79175ms","start":"2026-01-30T00:31:59.843412Z","end":"2026-01-30T00:32:00.326203Z","steps":["trace[1138145573] 'process raft request' (duration: 482.329947ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:32:00.326232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T00:31:59.843406Z","time spent":"482.81142ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T00:32:00.695157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.955955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T00:32:00.695363Z","caller":"traceutil/trace.go:171","msg":"trace[1637503646] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"294.03406ms","start":"2026-01-30T00:32:00.401293Z","end":"2026-01-30T00:32:00.695327Z","steps":["trace[1637503646] 'process raft request' (duration: 93.542912ms)","trace[1637503646] 'compare' (duration: 198.580344ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T00:35:07.313266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.951805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-01-30T00:35:07.291368Z","caller":"traceutil/trace.go:171","msg":"trace[1802818174] linearizableReadLoop","detail":"{readStateIndex:1839; appliedIndex:1838; }","duration":"252.0761ms","start":"2026-01-30T00:35:07.039271Z","end":"2026-01-30T00:35:07.291348Z","steps":["trace[1802818174] 'read index received' (duration: 251.891945ms)","trace[1802818174] 'applied index is now lower than readState.Index' (duration: 183.375µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T00:35:07.313351Z","caller":"traceutil/trace.go:171","msg":"trace[1559804512] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1762; }","duration":"274.080319ms","start":"2026-01-30T00:35:07.039255Z","end":"2026-01-30T00:35:07.313336Z","steps":["trace[1559804512] 'agreement among raft nodes before linearized reading' (duration: 273.843733ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T00:35:07.313568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.684616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-30T00:35:07.313605Z","caller":"traceutil/trace.go:171","msg":"trace[6134213] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:1762; }","duration":"210.768058ms","start":"2026-01-30T00:35:07.102825Z","end":"2026-01-30T00:35:07.313593Z","steps":["trace[6134213] 'agreement among raft nodes before linearized reading' (duration: 210.622254ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T00:35:07.291688Z","caller":"traceutil/trace.go:171","msg":"trace[1108289242] transaction","detail":"{read_only:false; response_revision:1762; number_of_response:1; }","duration":"260.324969ms","start":"2026-01-30T00:35:07.031334Z","end":"2026-01-30T00:35:07.291659Z","steps":["trace[1108289242] 'process raft request' (duration: 259.914747ms)"],"step_count":1}