{"level":"warn","ts":"2026-02-11T02:27:14.210592Z","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-11T02:27:14.21073Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210: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.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210: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-11T02:27:14.210838Z","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-11T02:27:14.210863Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-02-11T02:27:14.21089Z","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-11T02:27:14.211646Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-02-11T02:27:14.211743Z","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.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210: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-11T02:27:14.217671Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.672978ms"} {"level":"info","ts":"2026-02-11T02:27:14.225214Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-02-11T02:27:14.225317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-02-11T02:27:14.225344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-02-11T02:27:14.225356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-11T02:27:14.22537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-02-11T02:27:14.225416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-02-11T02:27:14.235406Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-11T02:27:14.240424Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-11T02:27:14.244987Z","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-11T02:27:14.253888Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-11T02:27:14.263972Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-11T02:27:14.264198Z","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-11T02:27:14.264252Z","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-11T02:27:14.264261Z","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-11T02:27:14.264884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-02-11T02:27:14.265115Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-02-11T02:27:14.274908Z","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-11T02:27:14.275154Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-02-11T02:27:14.275237Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-02-11T02:27:14.275671Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-11T02:27:14.275701Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-11T02:27:14.729347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-02-11T02:27:14.729863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-11T02:27:14.730074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-02-11T02:27:14.730211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-02-11T02:27:14.73029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-02-11T02:27:14.730441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-02-11T02:27:14.730523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-02-11T02:27:14.734977Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T02:27:14.735557Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T02:27:14.736096Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T02:27:14.735024Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-02-11T02:27:14.736399Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-11T02:27:14.736433Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-11T02:27:14.736899Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T02:27:14.738236Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T02:27:14.73829Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T02:27:14.748844Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-02-11T02:27:14.756798Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-11T02:27:35.508639Z","caller":"traceutil/trace.go:171","msg":"trace[1387496716] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:423; }","duration":"416.211501ms","start":"2026-02-11T02:27:35.092397Z","end":"2026-02-11T02:27:35.508608Z","steps":["trace[1387496716] 'read index received' (duration: 415.947255ms)","trace[1387496716] 'applied index is now lower than readState.Index' (duration: 263.186µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:35.50897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.519009ms","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-11T02:27:35.509144Z","caller":"traceutil/trace.go:171","msg":"trace[661926880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:411; }","duration":"416.753845ms","start":"2026-02-11T02:27:35.092369Z","end":"2026-02-11T02:27:35.509123Z","steps":["trace[661926880] 'agreement among raft nodes before linearized reading' (duration: 416.429757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:35.509195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.046582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-11T02:27:35.509207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:35.092288Z","time spent":"416.910029ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-11T02:27:35.509239Z","caller":"traceutil/trace.go:171","msg":"trace[1386611066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:411; }","duration":"410.093783ms","start":"2026-02-11T02:27:35.099131Z","end":"2026-02-11T02:27:35.509225Z","steps":["trace[1386611066] 'agreement among raft nodes before linearized reading' (duration: 410.042872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:35.509275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:35.099124Z","time spent":"410.143235ms","remote":"127.0.0.1:41986","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":"info","ts":"2026-02-11T02:27:35.509398Z","caller":"traceutil/trace.go:171","msg":"trace[1493966399] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"432.040519ms","start":"2026-02-11T02:27:35.077343Z","end":"2026-02-11T02:27:35.509384Z","steps":["trace[1493966399] 'process raft request' (duration: 431.087555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:35.509138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.950454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-11T02:27:35.509678Z","caller":"traceutil/trace.go:171","msg":"trace[1079239475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:411; }","duration":"411.519157ms","start":"2026-02-11T02:27:35.098147Z","end":"2026-02-11T02:27:35.509667Z","steps":["trace[1079239475] 'agreement among raft nodes before linearized reading' (duration: 410.929823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:35.509717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:35.09814Z","time spent":"411.565018ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T02:27:35.509983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:35.077329Z","time spent":"432.10654ms","remote":"127.0.0.1:41986","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-11T02:27:41.763156Z","caller":"traceutil/trace.go:171","msg":"trace[107324586] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"211.584637ms","start":"2026-02-11T02:27:41.551409Z","end":"2026-02-11T02:27:41.762994Z","steps":["trace[107324586] 'process raft request' (duration: 211.089575ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:27:41.763961Z","caller":"traceutil/trace.go:171","msg":"trace[701141273] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:439; }","duration":"128.656585ms","start":"2026-02-11T02:27:41.63402Z","end":"2026-02-11T02:27:41.762677Z","steps":["trace[701141273] 'read index received' (duration: 128.321487ms)","trace[701141273] 'applied index is now lower than readState.Index' (duration: 333.608µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:41.766278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.196583ms","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-11T02:27:41.766512Z","caller":"traceutil/trace.go:171","msg":"trace[1487337681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:426; }","duration":"130.414698ms","start":"2026-02-11T02:27:41.636033Z","end":"2026-02-11T02:27:41.766447Z","steps":["trace[1487337681] 'agreement among raft nodes before linearized reading' (duration: 130.162152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:41.766765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.759246ms","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-11T02:27:41.767142Z","caller":"traceutil/trace.go:171","msg":"trace[1339196249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:426; }","duration":"124.161656ms","start":"2026-02-11T02:27:41.642964Z","end":"2026-02-11T02:27:41.767126Z","steps":["trace[1339196249] 'agreement among raft nodes before linearized reading' (duration: 123.757616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:41.772895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.862695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-11T02:27:41.772938Z","caller":"traceutil/trace.go:171","msg":"trace[1632441108] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:426; }","duration":"138.914856ms","start":"2026-02-11T02:27:41.634011Z","end":"2026-02-11T02:27:41.772926Z","steps":["trace[1632441108] 'agreement among raft nodes before linearized reading' (duration: 138.801094ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:27:50.259433Z","caller":"traceutil/trace.go:171","msg":"trace[2083538836] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"449.36901ms","start":"2026-02-11T02:27:49.810015Z","end":"2026-02-11T02:27:50.259384Z","steps":["trace[2083538836] 'read index received' (duration: 449.035372ms)","trace[2083538836] 'applied index is now lower than readState.Index' (duration: 332.998µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:27:50.259602Z","caller":"traceutil/trace.go:171","msg":"trace[1388280341] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"465.821511ms","start":"2026-02-11T02:27:49.793772Z","end":"2026-02-11T02:27:50.259594Z","steps":["trace[1388280341] 'process raft request' (duration: 465.38137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:50.25973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:49.793761Z","time spent":"465.875752ms","remote":"127.0.0.1:41986","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-11T02:27:50.259806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.53783ms","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-11T02:27:50.259891Z","caller":"traceutil/trace.go:171","msg":"trace[84910438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:509; }","duration":"384.660283ms","start":"2026-02-11T02:27:49.875194Z","end":"2026-02-11T02:27:50.259854Z","steps":["trace[84910438] 'agreement among raft nodes before linearized reading' (duration: 384.53505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:50.259923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:49.875174Z","time spent":"384.740135ms","remote":"127.0.0.1:41986","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-11T02:27:50.260083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.539894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:50.260116Z","caller":"traceutil/trace.go:171","msg":"trace[867275865] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:509; }","duration":"330.602096ms","start":"2026-02-11T02:27:49.929502Z","end":"2026-02-11T02:27:50.260104Z","steps":["trace[867275865] 'agreement among raft nodes before linearized reading' (duration: 330.552475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:50.260128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.032018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-11T02:27:50.260141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:49.92947Z","time spent":"330.663368ms","remote":"127.0.0.1:41744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-02-11T02:27:50.260148Z","caller":"traceutil/trace.go:171","msg":"trace[813370446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:509; }","duration":"384.063879ms","start":"2026-02-11T02:27:49.876078Z","end":"2026-02-11T02:27:50.260142Z","steps":["trace[813370446] 'agreement among raft nodes before linearized reading' (duration: 384.011278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:50.260167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:49.876072Z","time spent":"384.090469ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T02:27:50.260338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.320893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-11T02:27:50.26036Z","caller":"traceutil/trace.go:171","msg":"trace[604299925] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:509; }","duration":"450.358624ms","start":"2026-02-11T02:27:49.809994Z","end":"2026-02-11T02:27:50.260353Z","steps":["trace[604299925] 'agreement among raft nodes before linearized reading' (duration: 450.267412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:50.260378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:49.809949Z","time spent":"450.424765ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T02:27:50.260383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.374177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:50.260433Z","caller":"traceutil/trace.go:171","msg":"trace[1534145222] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:509; }","duration":"130.428818ms","start":"2026-02-11T02:27:50.129967Z","end":"2026-02-11T02:27:50.260396Z","steps":["trace[1534145222] 'agreement among raft nodes before linearized reading' (duration: 130.395667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:51.399694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.17965ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:27:51.399978Z","caller":"traceutil/trace.go:171","msg":"trace[995709719] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:525; }","duration":"1.07021735s","start":"2026-02-11T02:27:50.329741Z","end":"2026-02-11T02:27:51.399959Z","steps":["trace[995709719] 'read index received' (duration: 436.531437ms)","trace[995709719] 'applied index is now lower than readState.Index' (duration: 633.684993ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:27:51.401262Z","caller":"traceutil/trace.go:171","msg":"trace[729859739] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.136539506s","start":"2026-02-11T02:27:50.26464Z","end":"2026-02-11T02:27:51.401179Z","steps":["trace[729859739] 'process raft request' (duration: 501.621533ms)","trace[729859739] 'compare' (duration: 632.520034ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:51.401505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:50.26462Z","time spent":"1.136751261s","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:27:51.404854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.764963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:51.40492Z","caller":"traceutil/trace.go:171","msg":"trace[590314779] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:513; }","duration":"873.850665ms","start":"2026-02-11T02:27:50.531041Z","end":"2026-02-11T02:27:51.404892Z","steps":["trace[590314779] 'agreement among raft nodes before linearized reading' (duration: 873.66125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:51.404962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:50.531019Z","time spent":"873.932356ms","remote":"127.0.0.1:41744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-11T02:27:51.405822Z","caller":"traceutil/trace.go:171","msg":"trace[956443528] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.141013555s","start":"2026-02-11T02:27:50.264784Z","end":"2026-02-11T02:27:51.405797Z","steps":["trace[956443528] 'process raft request' (duration: 1.13505798s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:51.405895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:50.264778Z","time spent":"1.141075326s","remote":"127.0.0.1:41986","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-11T02:27:51.406284Z","caller":"traceutil/trace.go:171","msg":"trace[1977186989] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"1.141430214s","start":"2026-02-11T02:27:50.26484Z","end":"2026-02-11T02:27:51.40627Z","steps":["trace[1977186989] 'process raft request' (duration: 1.135055339s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:51.407914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.655632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-11T02:27:51.407999Z","caller":"traceutil/trace.go:171","msg":"trace[1773222566] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:513; }","duration":"276.765225ms","start":"2026-02-11T02:27:51.131216Z","end":"2026-02-11T02:27:51.407981Z","steps":["trace[1773222566] 'agreement among raft nodes before linearized reading' (duration: 276.333385ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:27:51.408975Z","caller":"traceutil/trace.go:171","msg":"trace[707113308] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"758.829261ms","start":"2026-02-11T02:27:50.650114Z","end":"2026-02-11T02:27:51.408943Z","steps":["trace[707113308] 'process raft request' (duration: 749.812552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:51.409072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:50.650072Z","time spent":"758.952604ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:27:51.409364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079627658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:51.409433Z","caller":"traceutil/trace.go:171","msg":"trace[1044268448] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:513; }","duration":"1.07971606s","start":"2026-02-11T02:27:50.329694Z","end":"2026-02-11T02:27:51.40941Z","steps":["trace[1044268448] 'agreement among raft nodes before linearized reading' (duration: 1.079484515s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:51.409475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:50.329673Z","time spent":"1.079795363s","remote":"127.0.0.1:41744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-11T02:27:51.410644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.136438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-11T02:27:51.410717Z","caller":"traceutil/trace.go:171","msg":"trace[939513621] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:513; }","duration":"223.294351ms","start":"2026-02-11T02:27:51.187408Z","end":"2026-02-11T02:27:51.410703Z","steps":["trace[939513621] 'agreement among raft nodes before linearized reading' (duration: 223.068396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:51.411234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:50.264836Z","time spent":"1.146372825s","remote":"127.0.0.1:41986","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-11T02:27:51.413486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.151011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-11T02:27:51.413602Z","caller":"traceutil/trace.go:171","msg":"trace[1882236791] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:513; }","duration":"684.308395ms","start":"2026-02-11T02:27:50.729283Z","end":"2026-02-11T02:27:51.413592Z","steps":["trace[1882236791] 'agreement among raft nodes before linearized reading' (duration: 684.173852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:51.413652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:50.729263Z","time spent":"684.375037ms","remote":"127.0.0.1:41744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-11T02:27:51.413942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.250548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:51.418287Z","caller":"traceutil/trace.go:171","msg":"trace[638501242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"840.626724ms","start":"2026-02-11T02:27:50.57765Z","end":"2026-02-11T02:27:51.418277Z","steps":["trace[638501242] 'agreement among raft nodes before linearized reading' (duration: 836.267228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:51.41411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.870197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-11T02:27:51.414156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.442231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-11T02:27:51.419829Z","caller":"traceutil/trace.go:171","msg":"trace[1068313748] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:513; }","duration":"489.155489ms","start":"2026-02-11T02:27:50.930662Z","end":"2026-02-11T02:27:51.419817Z","steps":["trace[1068313748] 'agreement among raft nodes before linearized reading' (duration: 483.473731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:51.419883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:50.930637Z","time spent":"489.230472ms","remote":"127.0.0.1:41744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-11T02:27:51.419999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:50.577631Z","time spent":"842.363007ms","remote":"127.0.0.1:41704","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-11T02:27:51.420096Z","caller":"traceutil/trace.go:171","msg":"trace[25752294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"401.891933ms","start":"2026-02-11T02:27:51.018197Z","end":"2026-02-11T02:27:51.420089Z","steps":["trace[25752294] 'agreement among raft nodes before linearized reading' (duration: 395.896007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:51.421364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:51.018176Z","time spent":"403.180326ms","remote":"127.0.0.1:41716","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-11T02:27:52.495362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.46726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:27:52.495625Z","caller":"traceutil/trace.go:171","msg":"trace[2016114755] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:529; }","duration":"765.965485ms","start":"2026-02-11T02:27:51.729641Z","end":"2026-02-11T02:27:52.495607Z","steps":["trace[2016114755] 'read index received' (duration: 170.144406ms)","trace[2016114755] 'applied index is now lower than readState.Index' (duration: 595.817879ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:27:52.495843Z","caller":"traceutil/trace.go:171","msg":"trace[490500668] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.068961728s","start":"2026-02-11T02:27:51.426869Z","end":"2026-02-11T02:27:52.49583Z","steps":["trace[490500668] 'process raft request' (duration: 472.902224ms)","trace[490500668] 'compare' (duration: 594.555688ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:52.495914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:51.426852Z","time spent":"1.06902186s","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:27:52.496672Z","caller":"traceutil/trace.go:171","msg":"trace[977289786] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"927.712728ms","start":"2026-02-11T02:27:51.568924Z","end":"2026-02-11T02:27:52.496637Z","steps":["trace[977289786] 'process raft request' (duration: 926.634171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:52.496754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:51.568913Z","time spent":"927.80214ms","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4408,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:27:52.496933Z","caller":"traceutil/trace.go:171","msg":"trace[2007251197] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"964.982006ms","start":"2026-02-11T02:27:51.531941Z","end":"2026-02-11T02:27:52.496923Z","steps":["trace[2007251197] 'process raft request' (duration: 963.55116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:52.496981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:51.531917Z","time spent":"965.040017ms","remote":"127.0.0.1:41744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T02:27:52.49767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.019145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-11T02:27:52.497723Z","caller":"traceutil/trace.go:171","msg":"trace[1611880759] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:516; }","duration":"768.096597ms","start":"2026-02-11T02:27:51.729607Z","end":"2026-02-11T02:27:52.497703Z","steps":["trace[1611880759] 'agreement among raft nodes before linearized reading' (duration: 767.953914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:52.497797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:51.729589Z","time spent":"768.183699ms","remote":"127.0.0.1:41744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-11T02:27:52.996417Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493993005561207,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T02:27:53.497501Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493993005561207,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T02:27:53.998733Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493993005561207,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T02:27:54.021055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000200493s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-11T02:27:54.021169Z","caller":"traceutil/trace.go:171","msg":"trace[1606978351] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000370337s","start":"2026-02-11T02:27:52.020779Z","end":"2026-02-11T02:27:54.02115Z","steps":["trace[1606978351] 'agreement among raft nodes before linearized reading' (duration: 2.000228463s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:54.021302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:52.02076Z","time spent":"2.000451459s","remote":"127.0.0.1:41704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T02:27:54.499503Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493993005561207,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T02:27:54.708849Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.37351582s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-11T02:27:54.846852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.464873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:27:54.847183Z","caller":"traceutil/trace.go:171","msg":"trace[470902062] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"2.715413842s","start":"2026-02-11T02:27:52.131748Z","end":"2026-02-11T02:27:54.847162Z","steps":["trace[470902062] 'process raft request' (duration: 2.577441259s)","trace[470902062] 'compare' (duration: 132.180592ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:54.847269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:52.131723Z","time spent":"2.715505245s","remote":"127.0.0.1:41744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T02:27:54.847269Z","caller":"traceutil/trace.go:171","msg":"trace[1027949081] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"2.513286377s","start":"2026-02-11T02:27:52.333903Z","end":"2026-02-11T02:27:54.84719Z","steps":["trace[1027949081] 'process raft request' (duration: 2.513189585s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:54.85987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:52.333869Z","time spent":"2.525896114s","remote":"127.0.0.1:41744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T02:27:55.00095Z","caller":"traceutil/trace.go:171","msg":"trace[777146914] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"2.496582399s","start":"2026-02-11T02:27:52.50435Z","end":"2026-02-11T02:27:55.000932Z","steps":["trace[777146914] 'process raft request' (duration: 2.494326074s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:55.001092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:52.504325Z","time spent":"2.496689482s","remote":"127.0.0.1:41744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:27:55.001865Z","caller":"traceutil/trace.go:171","msg":"trace[240488342] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"2.432833355s","start":"2026-02-11T02:27:52.569023Z","end":"2026-02-11T02:27:55.001856Z","steps":["trace[240488342] 'process raft request' (duration: 2.432365334s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:55.001949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:52.568997Z","time spent":"2.432903157s","remote":"127.0.0.1:41744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T02:27:55.002526Z","caller":"traceutil/trace.go:171","msg":"trace[1735844897] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"2.245295534s","start":"2026-02-11T02:27:52.75722Z","end":"2026-02-11T02:27:55.002515Z","steps":["trace[1735844897] 'process raft request' (duration: 2.245068929s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:55.002639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:52.757182Z","time spent":"2.245369586s","remote":"127.0.0.1:41744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T02:27:55.094644Z","caller":"traceutil/trace.go:171","msg":"trace[935933953] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:532; }","duration":"2.598965044s","start":"2026-02-11T02:27:52.495659Z","end":"2026-02-11T02:27:55.094624Z","steps":["trace[935933953] 'read index received' (duration: 2.213595163s)","trace[935933953] 'applied index is now lower than readState.Index' (duration: 385.33505ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:55.09497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.830429224s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-11T02:27:55.094997Z","caller":"traceutil/trace.go:171","msg":"trace[1264316622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:521; }","duration":"2.830505506s","start":"2026-02-11T02:27:52.264484Z","end":"2026-02-11T02:27:55.094989Z","steps":["trace[1264316622] 'agreement among raft nodes before linearized reading' (duration: 2.830326051s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:55.09506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:52.264462Z","time spent":"2.830590607s","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T02:27:55.095409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.165462869s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-11T02:27:55.095435Z","caller":"traceutil/trace.go:171","msg":"trace[1187995150] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:521; }","duration":"3.16553037s","start":"2026-02-11T02:27:51.929896Z","end":"2026-02-11T02:27:55.095426Z","steps":["trace[1187995150] 'agreement among raft nodes before linearized reading' (duration: 3.165414177s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:55.095475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:51.929873Z","time spent":"3.165577151s","remote":"127.0.0.1:41744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-11T02:27:55.097678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.089295954s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-11T02:27:55.097711Z","caller":"traceutil/trace.go:171","msg":"trace[1449382599] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:523; }","duration":"2.089370116s","start":"2026-02-11T02:27:53.008333Z","end":"2026-02-11T02:27:55.097703Z","steps":["trace[1449382599] 'agreement among raft nodes before linearized reading' (duration: 2.089309154s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:55.097733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:53.008309Z","time spent":"2.089417777s","remote":"127.0.0.1:41744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-11T02:27:55.098009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.702253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-11T02:27:55.098029Z","caller":"traceutil/trace.go:171","msg":"trace[198839151] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:523; }","duration":"592.763385ms","start":"2026-02-11T02:27:54.505259Z","end":"2026-02-11T02:27:55.098023Z","steps":["trace[198839151] 'agreement among raft nodes before linearized reading' (duration: 592.725054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:55.098597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:54.505239Z","time spent":"593.347748ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T02:27:55.098738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227966874s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:55.098766Z","caller":"traceutil/trace.go:171","msg":"trace[1638764919] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:523; }","duration":"1.228001754s","start":"2026-02-11T02:27:53.870756Z","end":"2026-02-11T02:27:55.098758Z","steps":["trace[1638764919] 'agreement among raft nodes before linearized reading' (duration: 1.227956453s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:55.098786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:53.870753Z","time spent":"1.228027455s","remote":"127.0.0.1:54394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-11T02:27:55.098736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204778968s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-11T02:27:55.099146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.680721787s","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-11T02:27:55.09917Z","caller":"traceutil/trace.go:171","msg":"trace[249778246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:523; }","duration":"1.680806459s","start":"2026-02-11T02:27:53.418355Z","end":"2026-02-11T02:27:55.099162Z","steps":["trace[249778246] 'agreement among raft nodes before linearized reading' (duration: 1.680770678s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:55.099234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:53.41835Z","time spent":"1.680877951s","remote":"127.0.0.1:41986","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-11T02:27:55.099156Z","caller":"traceutil/trace.go:171","msg":"trace[1244511935] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:523; }","duration":"1.205204669s","start":"2026-02-11T02:27:53.893936Z","end":"2026-02-11T02:27:55.09914Z","steps":["trace[1244511935] 'agreement among raft nodes before linearized reading' (duration: 1.204602424s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:55.099498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.688368344s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-11T02:27:55.099525Z","caller":"traceutil/trace.go:171","msg":"trace[121591425] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:523; }","duration":"1.688437266s","start":"2026-02-11T02:27:53.411082Z","end":"2026-02-11T02:27:55.099519Z","steps":["trace[121591425] 'agreement among raft nodes before linearized reading' (duration: 1.688348653s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:55.099558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:53.411057Z","time spent":"1.688494886s","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T02:27:55.099742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.740237828s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:55.099767Z","caller":"traceutil/trace.go:171","msg":"trace[829924557] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:523; }","duration":"1.740288358s","start":"2026-02-11T02:27:53.359472Z","end":"2026-02-11T02:27:55.099761Z","steps":["trace[829924557] 'agreement among raft nodes before linearized reading' (duration: 1.740247057s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:55.099928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:53.35946Z","time spent":"1.740442873s","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 "} {"level":"warn","ts":"2026-02-11T02:27:55.099894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:53.893887Z","time spent":"1.205986838s","remote":"127.0.0.1:54404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"warn","ts":"2026-02-11T02:27:55.100152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.765639055s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:55.100179Z","caller":"traceutil/trace.go:171","msg":"trace[1919428946] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:523; }","duration":"1.765678437s","start":"2026-02-11T02:27:53.334494Z","end":"2026-02-11T02:27:55.100172Z","steps":["trace[1919428946] 'agreement among raft nodes before linearized reading' (duration: 1.765620836s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:55.100197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:53.334488Z","time spent":"1.765704348s","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-11T02:27:55.09909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.228332603s","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-02-11T02:27:55.10046Z","caller":"traceutil/trace.go:171","msg":"trace[945729385] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:523; }","duration":"1.229715476s","start":"2026-02-11T02:27:53.870736Z","end":"2026-02-11T02:27:55.100451Z","steps":["trace[945729385] 'agreement among raft nodes before linearized reading' (duration: 1.228332653s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:55.100507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:53.870733Z","time spent":"1.229760707s","remote":"127.0.0.1:54394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-02-11T02:27:55.099357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.681144197s","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-11T02:27:55.100668Z","caller":"traceutil/trace.go:171","msg":"trace[1716739289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:523; }","duration":"1.682474369s","start":"2026-02-11T02:27:53.418186Z","end":"2026-02-11T02:27:55.10066Z","steps":["trace[1716739289] 'agreement among raft nodes before linearized reading' (duration: 1.681149297s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:55.100721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:53.418175Z","time spent":"1.68253779s","remote":"127.0.0.1:41986","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-11T02:27:55.099116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.228393445s","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-02-11T02:27:55.102203Z","caller":"traceutil/trace.go:171","msg":"trace[1618768456] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:523; }","duration":"1.231448759s","start":"2026-02-11T02:27:53.870718Z","end":"2026-02-11T02:27:55.102167Z","steps":["trace[1618768456] 'agreement among raft nodes before linearized reading' (duration: 1.228387255s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:55.102244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:53.870688Z","time spent":"1.231543591s","remote":"127.0.0.1:54394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-02-11T02:27:55.168182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145862442s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:55.168243Z","caller":"traceutil/trace.go:171","msg":"trace[597862206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"1.145963204s","start":"2026-02-11T02:27:54.022267Z","end":"2026-02-11T02:27:55.16823Z","steps":["trace[597862206] 'agreement among raft nodes before linearized reading' (duration: 1.07805594s)","trace[597862206] 'range keys from in-memory index tree' (duration: 67.816642ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:55.168461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:54.022251Z","time spent":"1.146203561s","remote":"127.0.0.1:41716","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-11T02:27:55.168636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.240424626s","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-02-11T02:27:55.168663Z","caller":"traceutil/trace.go:171","msg":"trace[2103743545] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:523; }","duration":"1.240458866s","start":"2026-02-11T02:27:53.928195Z","end":"2026-02-11T02:27:55.168654Z","steps":["trace[2103743545] 'agreement among raft nodes before linearized reading' (duration: 1.172137742s)","trace[2103743545] 'range keys from in-memory index tree' (duration: 68.287164ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:55.168682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:53.928192Z","time spent":"1.240484908s","remote":"127.0.0.1:54432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-02-11T02:27:55.168778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.24058478s","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-02-11T02:27:55.1688Z","caller":"traceutil/trace.go:171","msg":"trace[109443883] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:523; }","duration":"1.240615791s","start":"2026-02-11T02:27:53.928178Z","end":"2026-02-11T02:27:55.168794Z","steps":["trace[109443883] 'agreement among raft nodes before linearized reading' (duration: 1.172160953s)","trace[109443883] 'count revisions from in-memory index tree' (duration: 68.427177ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:55.168819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:53.928174Z","time spent":"1.240639901s","remote":"127.0.0.1:54432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-11T02:27:55.168889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.240723163s","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-02-11T02:27:55.16891Z","caller":"traceutil/trace.go:171","msg":"trace[176562164] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:523; }","duration":"1.240759894s","start":"2026-02-11T02:27:53.928144Z","end":"2026-02-11T02:27:55.168904Z","steps":["trace[176562164] 'agreement among raft nodes before linearized reading' (duration: 1.172199994s)","trace[176562164] 'range keys from in-memory index tree' (duration: 68.53175ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:55.168931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:53.928138Z","time spent":"1.240787035s","remote":"127.0.0.1:54432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-02-11T02:27:55.169007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.245818459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:55.169028Z","caller":"traceutil/trace.go:171","msg":"trace[506509447] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:523; }","duration":"1.245848599s","start":"2026-02-11T02:27:53.923173Z","end":"2026-02-11T02:27:55.169022Z","steps":["trace[506509447] 'agreement among raft nodes before linearized reading' (duration: 1.177191066s)","trace[506509447] 'range keys from in-memory index tree' (duration: 68.627033ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:55.169046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:53.92317Z","time spent":"1.2458712s","remote":"127.0.0.1:54422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 "} {"level":"warn","ts":"2026-02-11T02:27:55.16913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.245960242s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:55.169147Z","caller":"traceutil/trace.go:171","msg":"trace[965560393] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:523; }","duration":"1.245988543s","start":"2026-02-11T02:27:53.923153Z","end":"2026-02-11T02:27:55.169142Z","steps":["trace[965560393] 'agreement among raft nodes before linearized reading' (duration: 1.177221257s)","trace[965560393] 'count revisions from in-memory index tree' (duration: 68.742476ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:55.169165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:53.92315Z","time spent":"1.246009623s","remote":"127.0.0.1:54422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-02-11T02:27:55.169235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246100535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:55.169252Z","caller":"traceutil/trace.go:171","msg":"trace[1391135867] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:523; }","duration":"1.246138437s","start":"2026-02-11T02:27:53.923108Z","end":"2026-02-11T02:27:55.169247Z","steps":["trace[1391135867] 'agreement among raft nodes before linearized reading' (duration: 1.177271618s)","trace[1391135867] 'range keys from in-memory index tree' (duration: 68.844588ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:55.169269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:53.923094Z","time spent":"1.246170677s","remote":"127.0.0.1:54422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 "} {"level":"warn","ts":"2026-02-11T02:27:55.169368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263236792s","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-02-11T02:27:55.169387Z","caller":"traceutil/trace.go:171","msg":"trace[599020699] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:523; }","duration":"1.263256903s","start":"2026-02-11T02:27:53.906124Z","end":"2026-02-11T02:27:55.169381Z","steps":["trace[599020699] 'agreement among raft nodes before linearized reading' (duration: 1.194261661s)","trace[599020699] 'range keys from in-memory index tree' (duration: 68.967751ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:55.169406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:53.906105Z","time spent":"1.263295394s","remote":"127.0.0.1:54418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-11T02:27:55.16954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263422917s","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-02-11T02:27:55.169614Z","caller":"traceutil/trace.go:171","msg":"trace[1365559817] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:523; }","duration":"1.263511009s","start":"2026-02-11T02:27:53.906087Z","end":"2026-02-11T02:27:55.169598Z","steps":["trace[1365559817] 'agreement among raft nodes before linearized reading' (duration: 1.194306382s)","trace[1365559817] 'count revisions from in-memory index tree' (duration: 69.116494ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:55.169678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:53.906081Z","time spent":"1.263563911s","remote":"127.0.0.1:54418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-11T02:27:55.169799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.27581956s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-11T02:27:55.169889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.835613871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:55.169943Z","caller":"traceutil/trace.go:171","msg":"trace[177647283] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:523; }","duration":"1.27585282s","start":"2026-02-11T02:27:53.893962Z","end":"2026-02-11T02:27:55.169815Z","steps":["trace[177647283] 'agreement among raft nodes before linearized reading' (duration: 1.206446549s)","trace[177647283] 'count revisions from in-memory index tree' (duration: 69.377241ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:27:55.169953Z","caller":"traceutil/trace.go:171","msg":"trace[1952691471] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:523; }","duration":"1.835711823s","start":"2026-02-11T02:27:53.334229Z","end":"2026-02-11T02:27:55.169941Z","steps":["trace[1952691471] 'agreement among raft nodes before linearized reading' (duration: 1.766570998s)","trace[1952691471] 'range keys from in-memory index tree' (duration: 69.065504ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:55.169972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:53.893957Z","time spent":"1.276006084s","remote":"127.0.0.1:54404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-02-11T02:27:55.170009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:53.334211Z","time spent":"1.835766674s","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 "} {"level":"warn","ts":"2026-02-11T02:27:55.170062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263997101s","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-02-11T02:27:55.170097Z","caller":"traceutil/trace.go:171","msg":"trace[497508540] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:523; }","duration":"1.264042862s","start":"2026-02-11T02:27:53.906031Z","end":"2026-02-11T02:27:55.170074Z","steps":["trace[497508540] 'agreement among raft nodes before linearized reading' (duration: 1.194366195s)","trace[497508540] 'range keys from in-memory index tree' (duration: 69.651907ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:55.170116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:53.906012Z","time spent":"1.264098303s","remote":"127.0.0.1:54418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-02-11T02:27:55.170198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276201659s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:55.170221Z","caller":"traceutil/trace.go:171","msg":"trace[791304112] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:523; }","duration":"1.2762317s","start":"2026-02-11T02:27:53.893981Z","end":"2026-02-11T02:27:55.170213Z","steps":["trace[791304112] 'agreement among raft nodes before linearized reading' (duration: 1.206422489s)","trace[791304112] 'range keys from in-memory index tree' (duration: 69.78175ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:55.170239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:53.893977Z","time spent":"1.27625623s","remote":"127.0.0.1:54404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"info","ts":"2026-02-11T02:27:56.223764Z","caller":"traceutil/trace.go:171","msg":"trace[10514072] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"185.406717ms","start":"2026-02-11T02:27:56.038341Z","end":"2026-02-11T02:27:56.223748Z","steps":["trace[10514072] 'read index received' (duration: 182.414805ms)","trace[10514072] 'applied index is now lower than readState.Index' (duration: 2.991292ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:56.224026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.671665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-02-11T02:27:56.224053Z","caller":"traceutil/trace.go:171","msg":"trace[276573414] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:536; }","duration":"185.754567ms","start":"2026-02-11T02:27:56.038289Z","end":"2026-02-11T02:27:56.224044Z","steps":["trace[276573414] 'agreement among raft nodes before linearized reading' (duration: 185.525461ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:27:56.224607Z","caller":"traceutil/trace.go:171","msg":"trace[1914640931] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"397.707701ms","start":"2026-02-11T02:27:55.826892Z","end":"2026-02-11T02:27:56.2246Z","steps":["trace[1914640931] 'process raft request' (duration: 394.050852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:56.224674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:55.826868Z","time spent":"397.757562ms","remote":"127.0.0.1:41744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:27:56.934115Z","caller":"traceutil/trace.go:171","msg":"trace[1968269577] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:553; }","duration":"241.811334ms","start":"2026-02-11T02:27:56.692287Z","end":"2026-02-11T02:27:56.934098Z","steps":["trace[1968269577] 'read index received' (duration: 241.615059ms)","trace[1968269577] 'applied index is now lower than readState.Index' (duration: 195.545µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:27:56.934214Z","caller":"traceutil/trace.go:171","msg":"trace[2106851509] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"271.775444ms","start":"2026-02-11T02:27:56.662432Z","end":"2026-02-11T02:27:56.934208Z","steps":["trace[2106851509] 'process raft request' (duration: 271.476306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:56.934429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.0886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:56.934452Z","caller":"traceutil/trace.go:171","msg":"trace[810878471] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:537; }","duration":"242.190543ms","start":"2026-02-11T02:27:56.692255Z","end":"2026-02-11T02:27:56.934445Z","steps":["trace[810878471] 'agreement among raft nodes before linearized reading' (duration: 242.092281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:56.934573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.218304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:56.934627Z","caller":"traceutil/trace.go:171","msg":"trace[1920477269] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:537; }","duration":"242.289215ms","start":"2026-02-11T02:27:56.692327Z","end":"2026-02-11T02:27:56.934616Z","steps":["trace[1920477269] 'agreement among raft nodes before linearized reading' (duration: 242.182713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:56.934692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.573361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:56.934725Z","caller":"traceutil/trace.go:171","msg":"trace[355260506] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:537; }","duration":"235.616002ms","start":"2026-02-11T02:27:56.699104Z","end":"2026-02-11T02:27:56.93472Z","steps":["trace[355260506] 'agreement among raft nodes before linearized reading' (duration: 235.569761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:56.934762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.740225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:56.93478Z","caller":"traceutil/trace.go:171","msg":"trace[1935398947] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:537; }","duration":"235.765915ms","start":"2026-02-11T02:27:56.699009Z","end":"2026-02-11T02:27:56.934775Z","steps":["trace[1935398947] 'agreement among raft nodes before linearized reading' (duration: 235.736504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:56.934852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.44615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-11T02:27:56.934867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.016841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:56.934881Z","caller":"traceutil/trace.go:171","msg":"trace[1382022763] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:537; }","duration":"208.512423ms","start":"2026-02-11T02:27:56.726364Z","end":"2026-02-11T02:27:56.934876Z","steps":["trace[1382022763] 'agreement among raft nodes before linearized reading' (duration: 208.444491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:56.934901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.553172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:56.934913Z","caller":"traceutil/trace.go:171","msg":"trace[1304882431] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:537; }","duration":"242.571012ms","start":"2026-02-11T02:27:56.692339Z","end":"2026-02-11T02:27:56.93491Z","steps":["trace[1304882431] 'agreement among raft nodes before linearized reading' (duration: 242.552652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:56.935081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.169608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:56.935097Z","caller":"traceutil/trace.go:171","msg":"trace[241781843] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:537; }","duration":"209.185179ms","start":"2026-02-11T02:27:56.725906Z","end":"2026-02-11T02:27:56.935091Z","steps":["trace[241781843] 'agreement among raft nodes before linearized reading' (duration: 209.160558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:56.935138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.961453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:56.93515Z","caller":"traceutil/trace.go:171","msg":"trace[360305545] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:537; }","duration":"208.974623ms","start":"2026-02-11T02:27:56.726172Z","end":"2026-02-11T02:27:56.935146Z","steps":["trace[360305545] 'agreement among raft nodes before linearized reading' (duration: 208.955673ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:27:56.934883Z","caller":"traceutil/trace.go:171","msg":"trace[1151557928] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:537; }","duration":"236.057493ms","start":"2026-02-11T02:27:56.698821Z","end":"2026-02-11T02:27:56.934878Z","steps":["trace[1151557928] 'agreement among raft nodes before linearized reading' (duration: 236.032492ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:27:59.405937Z","caller":"traceutil/trace.go:171","msg":"trace[368126132] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"153.583972ms","start":"2026-02-11T02:27:59.25233Z","end":"2026-02-11T02:27:59.405914Z","steps":["trace[368126132] 'process raft request' (duration: 96.86111ms)","trace[368126132] 'compare' (duration: 56.561788ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:27:59.406136Z","caller":"traceutil/trace.go:171","msg":"trace[1643298956] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"151.268816ms","start":"2026-02-11T02:27:59.254858Z","end":"2026-02-11T02:27:59.406127Z","steps":["trace[1643298956] 'process raft request' (duration: 151.01946ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:27:59.406612Z","caller":"traceutil/trace.go:171","msg":"trace[1175616061] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"151.677276ms","start":"2026-02-11T02:27:59.254923Z","end":"2026-02-11T02:27:59.4066Z","steps":["trace[1175616061] 'process raft request' (duration: 151.171683ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:27:59.40663Z","caller":"traceutil/trace.go:171","msg":"trace[1757513454] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"150.485267ms","start":"2026-02-11T02:27:59.256104Z","end":"2026-02-11T02:27:59.406589Z","steps":["trace[1757513454] 'process raft request' (duration: 150.375495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:03.602077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.189504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-11T02:28:03.602154Z","caller":"traceutil/trace.go:171","msg":"trace[1708422091] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"176.310246ms","start":"2026-02-11T02:28:03.42582Z","end":"2026-02-11T02:28:03.60213Z","steps":["trace[1708422091] 'range keys from in-memory index tree' (duration: 176.06306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:03.602462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.819976ms","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-11T02:28:03.602502Z","caller":"traceutil/trace.go:171","msg":"trace[757159152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"171.884888ms","start":"2026-02-11T02:28:03.430605Z","end":"2026-02-11T02:28:03.602489Z","steps":["trace[757159152] 'range keys from in-memory index tree' (duration: 171.727564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:03.602707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.980886ms","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-11T02:28:03.602741Z","caller":"traceutil/trace.go:171","msg":"trace[951792984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"171.027508ms","start":"2026-02-11T02:28:03.431703Z","end":"2026-02-11T02:28:03.60273Z","steps":["trace[951792984] 'range keys from in-memory index tree' (duration: 170.900774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:03.602918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.247363ms","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-11T02:28:03.60295Z","caller":"traceutil/trace.go:171","msg":"trace[1009010581] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:558; }","duration":"171.294414ms","start":"2026-02-11T02:28:03.431644Z","end":"2026-02-11T02:28:03.602938Z","steps":["trace[1009010581] 'range keys from in-memory index tree' (duration: 171.019977ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:28:03.750083Z","caller":"traceutil/trace.go:171","msg":"trace[173202797] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"121.502112ms","start":"2026-02-11T02:28:03.628556Z","end":"2026-02-11T02:28:03.750058Z","steps":["trace[173202797] 'process raft request' (duration: 121.278966ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:28:03.928629Z","caller":"traceutil/trace.go:171","msg":"trace[1468199618] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"299.754045ms","start":"2026-02-11T02:28:03.628844Z","end":"2026-02-11T02:28:03.928598Z","steps":["trace[1468199618] 'process raft request' (duration: 299.434907ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:28:03.928901Z","caller":"traceutil/trace.go:171","msg":"trace[777166384] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"300.092935ms","start":"2026-02-11T02:28:03.628795Z","end":"2026-02-11T02:28:03.928888Z","steps":["trace[777166384] 'process raft request' (duration: 252.024523ms)","trace[777166384] 'compare' (duration: 47.044816ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:28:03.928997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:03.628786Z","time spent":"300.140966ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:28:03.929788Z","caller":"traceutil/trace.go:171","msg":"trace[592874459] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"299.148311ms","start":"2026-02-11T02:28:03.630625Z","end":"2026-02-11T02:28:03.929773Z","steps":["trace[592874459] 'process raft request' (duration: 297.699405ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:28:03.929933Z","caller":"traceutil/trace.go:171","msg":"trace[1364372180] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:577; }","duration":"298.590777ms","start":"2026-02-11T02:28:03.631335Z","end":"2026-02-11T02:28:03.929925Z","steps":["trace[1364372180] 'read index received' (duration: 119.113083ms)","trace[1364372180] 'applied index is now lower than readState.Index' (duration: 179.476563ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:28:04.041894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.512647ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-11T02:28:04.04333Z","caller":"traceutil/trace.go:171","msg":"trace[1381008751] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:580; }","duration":"113.361452ms","start":"2026-02-11T02:28:03.929945Z","end":"2026-02-11T02:28:04.043307Z","steps":["trace[1381008751] 'read index received' (duration: 26.784902ms)","trace[1381008751] 'applied index is now lower than readState.Index' (duration: 86.5747ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:28:04.043441Z","caller":"traceutil/trace.go:171","msg":"trace[326998908] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"113.661079ms","start":"2026-02-11T02:28:03.92977Z","end":"2026-02-11T02:28:04.043431Z","steps":["trace[326998908] 'process raft request' (duration: 112.216744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:04.043758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.821051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6534"} {"level":"info","ts":"2026-02-11T02:28:04.043819Z","caller":"traceutil/trace.go:171","msg":"trace[1240466933] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:563; }","duration":"410.895014ms","start":"2026-02-11T02:28:03.632909Z","end":"2026-02-11T02:28:04.043804Z","steps":["trace[1240466933] 'agreement among raft nodes before linearized reading' (duration: 410.746479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:04.043865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:03.632903Z","time spent":"410.951265ms","remote":"127.0.0.1:41820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":6557,"request content":"key:\"/registry/configmaps/kube-system/cilium-config\" "} {"level":"warn","ts":"2026-02-11T02:28:04.042289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.939265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:28:04.049564Z","caller":"traceutil/trace.go:171","msg":"trace[883480172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:562; }","duration":"418.238193ms","start":"2026-02-11T02:28:03.631315Z","end":"2026-02-11T02:28:04.049553Z","steps":["trace[883480172] 'agreement among raft nodes before linearized reading' (duration: 298.657849ms)","trace[883480172] 'range keys from in-memory index tree' (duration: 112.284606ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:28:04.049586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:03.631308Z","time spent":"418.270354ms","remote":"127.0.0.1:41704","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-11T02:28:04.848026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.516894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:28:04.84814Z","caller":"traceutil/trace.go:171","msg":"trace[2028632617] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:563; }","duration":"733.663188ms","start":"2026-02-11T02:28:04.11445Z","end":"2026-02-11T02:28:04.848113Z","steps":["trace[2028632617] 'count revisions from in-memory index tree' (duration: 733.460043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:04.848246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:04.114447Z","time spent":"733.780661ms","remote":"127.0.0.1:34764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-11T02:28:04.848649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.322793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/kube-system/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:28:04.848725Z","caller":"traceutil/trace.go:171","msg":"trace[185697875] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/kube-system/; range_end:/registry/cilium.io/ciliumnodeconfigs/kube-system0; response_count:0; response_revision:563; }","duration":"734.412865ms","start":"2026-02-11T02:28:04.114293Z","end":"2026-02-11T02:28:04.848706Z","steps":["trace[185697875] 'range keys from in-memory index tree' (duration: 734.130799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:04.848768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:04.114271Z","time spent":"734.485278ms","remote":"127.0.0.1:34764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/kube-system/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs/kube-system0\" "} {"level":"warn","ts":"2026-02-11T02:28:04.848939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.55522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:28:04.848982Z","caller":"traceutil/trace.go:171","msg":"trace[642280438] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:563; }","duration":"734.627241ms","start":"2026-02-11T02:28:04.114342Z","end":"2026-02-11T02:28:04.848969Z","steps":["trace[642280438] 'range keys from in-memory index tree' (duration: 734.495817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:04.84902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:04.114339Z","time spent":"734.665502ms","remote":"127.0.0.1:34764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-02-11T02:28:05.521397Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493993005561307,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T02:28:05.760284Z","caller":"traceutil/trace.go:171","msg":"trace[588797944] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:582; }","duration":"739.833289ms","start":"2026-02-11T02:28:05.020358Z","end":"2026-02-11T02:28:05.760191Z","steps":["trace[588797944] 'read index received' (duration: 739.600013ms)","trace[588797944] 'applied index is now lower than readState.Index' (duration: 231.716µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:28:05.760506Z","caller":"traceutil/trace.go:171","msg":"trace[828214915] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"808.198794ms","start":"2026-02-11T02:28:04.952291Z","end":"2026-02-11T02:28:05.760489Z","steps":["trace[828214915] 'process raft request' (duration: 807.659631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:05.760663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:04.952273Z","time spent":"808.281636ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:28:05.76092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.346717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-11T02:28:05.760995Z","caller":"traceutil/trace.go:171","msg":"trace[1485349673] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:564; }","duration":"485.45394ms","start":"2026-02-11T02:28:05.27552Z","end":"2026-02-11T02:28:05.760974Z","steps":["trace[1485349673] 'agreement among raft nodes before linearized reading' (duration: 485.236834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:05.761037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:05.275507Z","time spent":"485.520952ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T02:28:05.761162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.804023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:28:05.761216Z","caller":"traceutil/trace.go:171","msg":"trace[637549851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:564; }","duration":"740.885615ms","start":"2026-02-11T02:28:05.020314Z","end":"2026-02-11T02:28:05.7612Z","steps":["trace[637549851] 'agreement among raft nodes before linearized reading' (duration: 740.798373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:05.76128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:05.020298Z","time spent":"740.971157ms","remote":"127.0.0.1:41704","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-11T02:28:06.778759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.322012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:28:06.778896Z","caller":"traceutil/trace.go:171","msg":"trace[962940486] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:583; }","duration":"845.563165ms","start":"2026-02-11T02:28:05.933322Z","end":"2026-02-11T02:28:06.778885Z","steps":["trace[962940486] 'read index received' (duration: 425.091389ms)","trace[962940486] 'applied index is now lower than readState.Index' (duration: 420.470916ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:28:06.779049Z","caller":"traceutil/trace.go:171","msg":"trace[1483310145] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"1.006598639s","start":"2026-02-11T02:28:05.772441Z","end":"2026-02-11T02:28:06.77904Z","steps":["trace[1483310145] 'process raft request' (duration: 585.934878ms)","trace[1483310145] 'compare' (duration: 420.028065ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:28:06.779099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:05.772422Z","time spent":"1.0066504s","remote":"127.0.0.1:41986","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-11T02:28:06.779262Z","caller":"traceutil/trace.go:171","msg":"trace[1227849396] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.003872633s","start":"2026-02-11T02:28:05.775378Z","end":"2026-02-11T02:28:06.779251Z","steps":["trace[1227849396] 'process raft request' (duration: 1.003455493s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:06.779308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:05.775363Z","time spent":"1.003922053s","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:28:06.77941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.088287ms","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-11T02:28:06.779435Z","caller":"traceutil/trace.go:171","msg":"trace[230649414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:566; }","duration":"846.151539ms","start":"2026-02-11T02:28:05.933276Z","end":"2026-02-11T02:28:06.779428Z","steps":["trace[230649414] 'agreement among raft nodes before linearized reading' (duration: 846.106248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:06.77946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:05.933258Z","time spent":"846.19798ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T02:28:06.77961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.723959ms","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-11T02:28:06.779632Z","caller":"traceutil/trace.go:171","msg":"trace[627553157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:566; }","duration":"845.76748ms","start":"2026-02-11T02:28:05.93386Z","end":"2026-02-11T02:28:06.779627Z","steps":["trace[627553157] 'agreement among raft nodes before linearized reading' (duration: 845.685008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:06.779649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:05.933848Z","time spent":"845.797381ms","remote":"127.0.0.1:41986","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-11T02:28:06.779794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.042271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-11T02:28:06.779818Z","caller":"traceutil/trace.go:171","msg":"trace[1725045621] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:566; }","duration":"845.087733ms","start":"2026-02-11T02:28:05.934725Z","end":"2026-02-11T02:28:06.779813Z","steps":["trace[1725045621] 'agreement among raft nodes before linearized reading' (duration: 845.046622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:06.779837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:05.934712Z","time spent":"845.121174ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-11T02:28:11.079764Z","caller":"traceutil/trace.go:171","msg":"trace[548623783] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"230.990708ms","start":"2026-02-11T02:28:10.848751Z","end":"2026-02-11T02:28:11.079742Z","steps":["trace[548623783] 'read index received' (duration: 220.38543ms)","trace[548623783] 'applied index is now lower than readState.Index' (duration: 10.604138ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:28:11.079951Z","caller":"traceutil/trace.go:171","msg":"trace[664566812] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"277.652785ms","start":"2026-02-11T02:28:10.80229Z","end":"2026-02-11T02:28:11.079943Z","steps":["trace[664566812] 'process raft request' (duration: 266.913514ms)","trace[664566812] 'compare' (duration: 10.229519ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:28:11.080144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.385248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-11T02:28:11.080163Z","caller":"traceutil/trace.go:171","msg":"trace[1800607087] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:594; }","duration":"231.457589ms","start":"2026-02-11T02:28:10.848698Z","end":"2026-02-11T02:28:11.080156Z","steps":["trace[1800607087] 'agreement among raft nodes before linearized reading' (duration: 231.357197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:11.080295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.451492ms","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-11T02:28:11.080311Z","caller":"traceutil/trace.go:171","msg":"trace[1988299980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"213.467922ms","start":"2026-02-11T02:28:10.866839Z","end":"2026-02-11T02:28:11.080307Z","steps":["trace[1988299980] 'agreement among raft nodes before linearized reading' (duration: 213.422871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:11.080776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.357031ms","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-11T02:28:11.080795Z","caller":"traceutil/trace.go:171","msg":"trace[554157890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:594; }","duration":"211.390712ms","start":"2026-02-11T02:28:10.869399Z","end":"2026-02-11T02:28:11.080789Z","steps":["trace[554157890] 'agreement among raft nodes before linearized reading' (duration: 211.143795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:11.440292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.494263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:28:11.440684Z","caller":"traceutil/trace.go:171","msg":"trace[584451630] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"351.131987ms","start":"2026-02-11T02:28:11.089439Z","end":"2026-02-11T02:28:11.440571Z","steps":["trace[584451630] 'process raft request' (duration: 90.121516ms)","trace[584451630] 'compare' (duration: 259.011372ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:28:11.440797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:11.089416Z","time spent":"351.329281ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:28:11.443179Z","caller":"traceutil/trace.go:171","msg":"trace[2091657004] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"348.618366ms","start":"2026-02-11T02:28:11.094547Z","end":"2026-02-11T02:28:11.443165Z","steps":["trace[2091657004] 'process raft request' (duration: 347.289143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:11.443273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:11.094535Z","time spent":"348.689087ms","remote":"127.0.0.1:41986","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-11T02:28:11.44518Z","caller":"traceutil/trace.go:171","msg":"trace[380601997] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"347.889908ms","start":"2026-02-11T02:28:11.097272Z","end":"2026-02-11T02:28:11.445162Z","steps":["trace[380601997] 'process raft request' (duration: 344.651808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:11.445268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:11.097248Z","time spent":"347.98115ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:28:11.452464Z","caller":"traceutil/trace.go:171","msg":"trace[1370015243] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"351.588177ms","start":"2026-02-11T02:28:11.10086Z","end":"2026-02-11T02:28:11.452448Z","steps":["trace[1370015243] 'process raft request' (duration: 342.26623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:11.452697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:11.100849Z","time spent":"351.666939ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17817,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:28:13.073061Z","caller":"traceutil/trace.go:171","msg":"trace[895373109] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"242.420988ms","start":"2026-02-11T02:28:12.830618Z","end":"2026-02-11T02:28:13.073039Z","steps":["trace[895373109] 'process raft request' (duration: 242.044419ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:28:13.241575Z","caller":"traceutil/trace.go:171","msg":"trace[1480410754] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"154.061894ms","start":"2026-02-11T02:28:13.087496Z","end":"2026-02-11T02:28:13.241557Z","steps":["trace[1480410754] 'process raft request' (duration: 153.946941ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:28:13.245665Z","caller":"traceutil/trace.go:171","msg":"trace[506609971] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"156.328149ms","start":"2026-02-11T02:28:13.089326Z","end":"2026-02-11T02:28:13.245654Z","steps":["trace[506609971] 'process raft request' (duration: 156.271388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:15.867442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.616286ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:28:15.86761Z","caller":"traceutil/trace.go:171","msg":"trace[1701093409] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:622; }","duration":"203.79851ms","start":"2026-02-11T02:28:15.663794Z","end":"2026-02-11T02:28:15.867592Z","steps":["trace[1701093409] 'range keys from in-memory index tree' (duration: 203.595705ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:28:15.867903Z","caller":"traceutil/trace.go:171","msg":"trace[1095784643] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"219.987377ms","start":"2026-02-11T02:28:15.647875Z","end":"2026-02-11T02:28:15.867862Z","steps":["trace[1095784643] 'process raft request' (duration: 162.621263ms)","trace[1095784643] 'compare' (duration: 57.221301ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:28:21.173162Z","caller":"traceutil/trace.go:171","msg":"trace[281042294] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"183.108134ms","start":"2026-02-11T02:28:20.990017Z","end":"2026-02-11T02:28:21.173125Z","steps":["trace[281042294] 'process raft request' (duration: 182.942741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:22.412567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.049714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:28:22.412737Z","caller":"traceutil/trace.go:171","msg":"trace[343106130] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:679; }","duration":"1.136662957s","start":"2026-02-11T02:28:21.276059Z","end":"2026-02-11T02:28:22.412722Z","steps":["trace[343106130] 'read index received' (duration: 456.387268ms)","trace[343106130] 'applied index is now lower than readState.Index' (duration: 680.274579ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:28:22.412903Z","caller":"traceutil/trace.go:171","msg":"trace[388372021] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"1.230794582s","start":"2026-02-11T02:28:21.182098Z","end":"2026-02-11T02:28:22.412892Z","steps":["trace[388372021] 'process raft request' (duration: 550.332178ms)","trace[388372021] 'compare' (duration: 679.313926ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:28:22.413047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136997306s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-11T02:28:22.41307Z","caller":"traceutil/trace.go:171","msg":"trace[1939037181] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:657; }","duration":"1.137058127s","start":"2026-02-11T02:28:21.276005Z","end":"2026-02-11T02:28:22.413063Z","steps":["trace[1939037181] 'agreement among raft nodes before linearized reading' (duration: 1.136957585s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:22.413094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:21.275982Z","time spent":"1.137106818s","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-11T02:28:22.413409Z","caller":"traceutil/trace.go:171","msg":"trace[1060332026] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"1.22906787s","start":"2026-02-11T02:28:21.184331Z","end":"2026-02-11T02:28:22.413398Z","steps":["trace[1060332026] 'process raft request' (duration: 1.228348423s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:22.413446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:21.184302Z","time spent":"1.229125652s","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":799,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T02:28:22.413716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.808839ms","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-11T02:28:22.413739Z","caller":"traceutil/trace.go:171","msg":"trace[1143246322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:658; }","duration":"915.86493ms","start":"2026-02-11T02:28:21.497867Z","end":"2026-02-11T02:28:22.413732Z","steps":["trace[1143246322] 'agreement among raft nodes before linearized reading' (duration: 915.776908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:22.41376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:21.497847Z","time spent":"915.908251ms","remote":"127.0.0.1:41986","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":"info","ts":"2026-02-11T02:28:22.41384Z","caller":"traceutil/trace.go:171","msg":"trace[626713887] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"579.23872ms","start":"2026-02-11T02:28:21.834596Z","end":"2026-02-11T02:28:22.413835Z","steps":["trace[626713887] 'process raft request' (duration: 579.008935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:22.413876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:21.834572Z","time spent":"579.282201ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:28:22.413928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.847368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:28:22.413943Z","caller":"traceutil/trace.go:171","msg":"trace[1130127925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:658; }","duration":"395.9136ms","start":"2026-02-11T02:28:22.018025Z","end":"2026-02-11T02:28:22.413938Z","steps":["trace[1130127925] 'agreement among raft nodes before linearized reading' (duration: 395.890329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:22.413958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:22.018003Z","time spent":"395.952081ms","remote":"127.0.0.1:41704","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-11T02:28:22.414083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.994083ms","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-11T02:28:22.4141Z","caller":"traceutil/trace.go:171","msg":"trace[115808578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:658; }","duration":"916.059544ms","start":"2026-02-11T02:28:21.498035Z","end":"2026-02-11T02:28:22.414095Z","steps":["trace[115808578] 'agreement among raft nodes before linearized reading' (duration: 916.005343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:22.414116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:21.497985Z","time spent":"916.128397ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T02:28:22.414193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.210639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-11T02:28:22.414208Z","caller":"traceutil/trace.go:171","msg":"trace[1841575210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:658; }","duration":"916.236809ms","start":"2026-02-11T02:28:21.497967Z","end":"2026-02-11T02:28:22.414203Z","steps":["trace[1841575210] 'agreement among raft nodes before linearized reading' (duration: 916.190208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:22.414223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:21.497963Z","time spent":"916.256959ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T02:28:22.415602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:21.182076Z","time spent":"1.230850043s","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:28:30.969455Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493993005561624,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T02:28:31.121286Z","caller":"traceutil/trace.go:171","msg":"trace[345806099] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:748; }","duration":"652.828961ms","start":"2026-02-11T02:28:30.468427Z","end":"2026-02-11T02:28:31.121256Z","steps":["trace[345806099] 'read index received' (duration: 652.587856ms)","trace[345806099] 'applied index is now lower than readState.Index' (duration: 240.255µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:28:31.121668Z","caller":"traceutil/trace.go:171","msg":"trace[1846725122] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"662.766179ms","start":"2026-02-11T02:28:30.458892Z","end":"2026-02-11T02:28:31.121658Z","steps":["trace[1846725122] 'process raft request' (duration: 662.211026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:31.121782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:30.458863Z","time spent":"662.835201ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:28:31.122126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.695143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-11T02:28:31.122374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.749092ms","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-11T02:28:31.122411Z","caller":"traceutil/trace.go:171","msg":"trace[1316174213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:723; }","duration":"640.820294ms","start":"2026-02-11T02:28:30.48158Z","end":"2026-02-11T02:28:31.122401Z","steps":["trace[1316174213] 'agreement among raft nodes before linearized reading' (duration: 640.740992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:31.122437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:30.481564Z","time spent":"640.867696ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T02:28:31.122736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.482677ms","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-11T02:28:31.122761Z","caller":"traceutil/trace.go:171","msg":"trace[1269855837] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:723; }","duration":"587.519978ms","start":"2026-02-11T02:28:30.535234Z","end":"2026-02-11T02:28:31.122754Z","steps":["trace[1269855837] 'agreement among raft nodes before linearized reading' (duration: 587.461936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:31.12278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:30.535223Z","time spent":"587.552149ms","remote":"127.0.0.1:41986","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":"warn","ts":"2026-02-11T02:28:31.122897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.876412ms","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-11T02:28:31.12292Z","caller":"traceutil/trace.go:171","msg":"trace[1612087136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:723; }","duration":"591.934803ms","start":"2026-02-11T02:28:30.530978Z","end":"2026-02-11T02:28:31.122913Z","steps":["trace[1612087136] 'agreement among raft nodes before linearized reading' (duration: 591.876992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:31.122939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:30.530963Z","time spent":"591.969174ms","remote":"127.0.0.1:41986","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":"info","ts":"2026-02-11T02:28:31.123813Z","caller":"traceutil/trace.go:171","msg":"trace[1224800004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:723; }","duration":"655.415203ms","start":"2026-02-11T02:28:30.468388Z","end":"2026-02-11T02:28:31.123803Z","steps":["trace[1224800004] 'agreement among raft nodes before linearized reading' (duration: 653.59224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:31.123846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:30.46837Z","time spent":"655.465894ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-11T02:28:32.229877Z","caller":"traceutil/trace.go:171","msg":"trace[738257819] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"639.275087ms","start":"2026-02-11T02:28:31.590577Z","end":"2026-02-11T02:28:32.229852Z","steps":["trace[738257819] 'process raft request' (duration: 639.167205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:32.230035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:31.590552Z","time spent":"639.42327ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T02:28:32.230463Z","caller":"traceutil/trace.go:171","msg":"trace[358656418] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:754; }","duration":"214.482105ms","start":"2026-02-11T02:28:32.015971Z","end":"2026-02-11T02:28:32.230453Z","steps":["trace[358656418] 'read index received' (duration: 214.476785ms)","trace[358656418] 'applied index is now lower than readState.Index' (duration: 4.39µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:28:32.230538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.563517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:28:32.230564Z","caller":"traceutil/trace.go:171","msg":"trace[41755741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:728; }","duration":"214.632699ms","start":"2026-02-11T02:28:32.015922Z","end":"2026-02-11T02:28:32.230554Z","steps":["trace[41755741] 'agreement among raft nodes before linearized reading' (duration: 214.565397ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:28:33.496541Z","caller":"traceutil/trace.go:171","msg":"trace[1694211050] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"309.030469ms","start":"2026-02-11T02:28:33.187489Z","end":"2026-02-11T02:28:33.49652Z","steps":["trace[1694211050] 'process raft request' (duration: 308.897796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:33.49675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:33.187474Z","time spent":"309.197414ms","remote":"127.0.0.1:41986","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-11T02:28:33.502783Z","caller":"traceutil/trace.go:171","msg":"trace[96784996] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"314.167892ms","start":"2026-02-11T02:28:33.188591Z","end":"2026-02-11T02:28:33.502759Z","steps":["trace[96784996] 'process raft request' (duration: 313.951997ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:28:33.50308Z","caller":"traceutil/trace.go:171","msg":"trace[346740655] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"311.734095ms","start":"2026-02-11T02:28:33.191325Z","end":"2026-02-11T02:28:33.503059Z","steps":["trace[346740655] 'process raft request' (duration: 311.639702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:33.503204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:33.188577Z","time spent":"314.504582ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:28:33.503215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:33.191316Z","time spent":"311.846328ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:28:33.503514Z","caller":"traceutil/trace.go:171","msg":"trace[198366675] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"314.299947ms","start":"2026-02-11T02:28:33.189203Z","end":"2026-02-11T02:28:33.503503Z","steps":["trace[198366675] 'process raft request' (duration: 313.532448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:33.503577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:33.189196Z","time spent":"314.350768ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:28:35.629533Z","caller":"traceutil/trace.go:171","msg":"trace[1537443985] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"361.911597ms","start":"2026-02-11T02:28:35.267595Z","end":"2026-02-11T02:28:35.629507Z","steps":["trace[1537443985] 'read index received' (duration: 361.64029ms)","trace[1537443985] 'applied index is now lower than readState.Index' (duration: 270.507µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:28:35.629794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.162383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:28:35.629897Z","caller":"traceutil/trace.go:171","msg":"trace[966305471] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:735; }","duration":"362.307246ms","start":"2026-02-11T02:28:35.267551Z","end":"2026-02-11T02:28:35.629858Z","steps":["trace[966305471] 'agreement among raft nodes before linearized reading' (duration: 362.153913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:35.629942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:35.267533Z","time spent":"362.398468ms","remote":"127.0.0.1:41956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-11T02:28:35.629963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.969624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-11T02:28:35.630004Z","caller":"traceutil/trace.go:171","msg":"trace[658561108] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:735; }","duration":"128.086827ms","start":"2026-02-11T02:28:35.501906Z","end":"2026-02-11T02:28:35.629993Z","steps":["trace[658561108] 'agreement among raft nodes before linearized reading' (duration: 127.920513ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:28:35.630225Z","caller":"traceutil/trace.go:171","msg":"trace[250305153] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"485.29859ms","start":"2026-02-11T02:28:35.144914Z","end":"2026-02-11T02:28:35.630212Z","steps":["trace[250305153] 'process raft request' (duration: 484.41188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:35.630245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.671808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-11T02:28:35.630274Z","caller":"traceutil/trace.go:171","msg":"trace[995688295] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:735; }","duration":"122.730319ms","start":"2026-02-11T02:28:35.507535Z","end":"2026-02-11T02:28:35.630266Z","steps":["trace[995688295] 'agreement among raft nodes before linearized reading' (duration: 122.654417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:35.630355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:35.144886Z","time spent":"485.367481ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:28:35.630461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.108248ms","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-11T02:28:35.630484Z","caller":"traceutil/trace.go:171","msg":"trace[1101505027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:735; }","duration":"123.138239ms","start":"2026-02-11T02:28:35.50734Z","end":"2026-02-11T02:28:35.630478Z","steps":["trace[1101505027] 'agreement among raft nodes before linearized reading' (duration: 123.080517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:35.630574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.238121ms","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-11T02:28:35.630589Z","caller":"traceutil/trace.go:171","msg":"trace[1696853362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:735; }","duration":"123.269701ms","start":"2026-02-11T02:28:35.507315Z","end":"2026-02-11T02:28:35.630585Z","steps":["trace[1696853362] 'agreement among raft nodes before linearized reading' (duration: 123.22341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:35.855371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.475376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:28:35.855576Z","caller":"traceutil/trace.go:171","msg":"trace[533422606] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"219.159927ms","start":"2026-02-11T02:28:35.636383Z","end":"2026-02-11T02:28:35.855543Z","steps":["trace[533422606] 'process raft request' (duration: 100.407074ms)","trace[533422606] 'compare' (duration: 118.310503ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:28:36.018435Z","caller":"traceutil/trace.go:171","msg":"trace[1850944281] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"379.871305ms","start":"2026-02-11T02:28:35.638546Z","end":"2026-02-11T02:28:36.018417Z","steps":["trace[1850944281] 'process raft request' (duration: 379.819744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:36.018585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:35.638537Z","time spent":"379.963347ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:28:36.018717Z","caller":"traceutil/trace.go:171","msg":"trace[2038267091] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"382.09743ms","start":"2026-02-11T02:28:35.636614Z","end":"2026-02-11T02:28:36.018712Z","steps":["trace[2038267091] 'process raft request' (duration: 381.522585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:36.018744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:35.636607Z","time spent":"382.12234ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:28:36.018777Z","caller":"traceutil/trace.go:171","msg":"trace[1408657845] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"370.883881ms","start":"2026-02-11T02:28:35.647883Z","end":"2026-02-11T02:28:36.018767Z","steps":["trace[1408657845] 'process raft request' (duration: 370.510621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:36.018845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:35.647857Z","time spent":"370.961453ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:28:38.09648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.712318ms","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-11T02:28:38.096585Z","caller":"traceutil/trace.go:171","msg":"trace[2104214896] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:740; }","duration":"236.869502ms","start":"2026-02-11T02:28:37.859695Z","end":"2026-02-11T02:28:38.096565Z","steps":["trace[2104214896] 'range keys from in-memory index tree' (duration: 236.599825ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:28:38.449013Z","caller":"traceutil/trace.go:171","msg":"trace[1099883259] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"345.852871ms","start":"2026-02-11T02:28:38.103076Z","end":"2026-02-11T02:28:38.448929Z","steps":["trace[1099883259] 'process raft request' (duration: 345.704287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:38.449124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:38.103063Z","time spent":"346.002594ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:28:38.449247Z","caller":"traceutil/trace.go:171","msg":"trace[810018371] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"343.659209ms","start":"2026-02-11T02:28:38.105565Z","end":"2026-02-11T02:28:38.449224Z","steps":["trace[810018371] 'process raft request' (duration: 343.342741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:38.449368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:38.10555Z","time spent":"343.753202ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:28:38.449408Z","caller":"traceutil/trace.go:171","msg":"trace[319252580] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:768; }","duration":"100.360873ms","start":"2026-02-11T02:28:38.34904Z","end":"2026-02-11T02:28:38.4494Z","steps":["trace[319252580] 'read index received' (duration: 99.676757ms)","trace[319252580] 'applied index is now lower than readState.Index' (duration: 683.516µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:28:38.449453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.410854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:28:38.44947Z","caller":"traceutil/trace.go:171","msg":"trace[516636494] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:744; }","duration":"100.474525ms","start":"2026-02-11T02:28:38.348991Z","end":"2026-02-11T02:28:38.449465Z","steps":["trace[516636494] 'agreement among raft nodes before linearized reading' (duration: 100.433714ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:28:38.449593Z","caller":"traceutil/trace.go:171","msg":"trace[239665701] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"342.079721ms","start":"2026-02-11T02:28:38.107506Z","end":"2026-02-11T02:28:38.449585Z","steps":["trace[239665701] 'process raft request' (duration: 341.650881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:38.449619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:38.107499Z","time spent":"342.104542ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:28:43.811338Z","caller":"traceutil/trace.go:171","msg":"trace[1777385324] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"140.538326ms","start":"2026-02-11T02:28:43.670762Z","end":"2026-02-11T02:28:43.8113Z","steps":["trace[1777385324] 'process raft request' (duration: 140.371392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:45.275635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.064462ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-11T02:28:45.275753Z","caller":"traceutil/trace.go:171","msg":"trace[1132963641] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:792; }","duration":"1.256521517s","start":"2026-02-11T02:28:44.019211Z","end":"2026-02-11T02:28:45.275733Z","steps":["trace[1132963641] 'read index received' (duration: 432.281461ms)","trace[1132963641] 'applied index is now lower than readState.Index' (duration: 824.238406ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:28:45.276639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.257412869s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:28:45.276689Z","caller":"traceutil/trace.go:171","msg":"trace[2064158878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:763; }","duration":"1.257513441s","start":"2026-02-11T02:28:44.019161Z","end":"2026-02-11T02:28:45.276675Z","steps":["trace[2064158878] 'agreement among raft nodes before linearized reading' (duration: 1.257396349s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:45.276742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:44.019138Z","time spent":"1.257578512s","remote":"127.0.0.1:41704","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-11T02:28:45.277167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102603131s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-11T02:28:45.277208Z","caller":"traceutil/trace.go:171","msg":"trace[1017843147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:763; }","duration":"1.102686894s","start":"2026-02-11T02:28:44.174512Z","end":"2026-02-11T02:28:45.277199Z","steps":["trace[1017843147] 'agreement among raft nodes before linearized reading' (duration: 1.102544289s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:45.277234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:44.174488Z","time spent":"1.102739355s","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T02:28:45.277417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.450411ms","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-11T02:28:45.277454Z","caller":"traceutil/trace.go:171","msg":"trace[1463671825] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:763; }","duration":"768.485682ms","start":"2026-02-11T02:28:44.508958Z","end":"2026-02-11T02:28:45.277444Z","steps":["trace[1463671825] 'agreement among raft nodes before linearized reading' (duration: 768.39928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:45.277481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:44.508941Z","time spent":"768.532633ms","remote":"127.0.0.1:41986","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":"warn","ts":"2026-02-11T02:28:45.277638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.528032ms","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-11T02:28:45.277672Z","caller":"traceutil/trace.go:171","msg":"trace[2066562024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:763; }","duration":"778.627254ms","start":"2026-02-11T02:28:44.499037Z","end":"2026-02-11T02:28:45.277665Z","steps":["trace[2066562024] 'agreement among raft nodes before linearized reading' (duration: 778.548952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:45.277703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:44.49892Z","time spent":"778.776858ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T02:28:45.277854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.130746ms","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-11T02:28:45.277887Z","caller":"traceutil/trace.go:171","msg":"trace[1211344934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:763; }","duration":"779.191477ms","start":"2026-02-11T02:28:44.498688Z","end":"2026-02-11T02:28:45.27788Z","steps":["trace[1211344934] 'agreement among raft nodes before linearized reading' (duration: 779.112715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:45.277911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:28:44.498669Z","time spent":"779.236718ms","remote":"127.0.0.1:41986","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":"info","ts":"2026-02-11T02:28:45.393342Z","caller":"traceutil/trace.go:171","msg":"trace[633163174] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"107.400201ms","start":"2026-02-11T02:28:45.285917Z","end":"2026-02-11T02:28:45.393317Z","steps":["trace[633163174] 'process raft request' (duration: 107.184645ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:28:45.493057Z","caller":"traceutil/trace.go:171","msg":"trace[140147471] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:794; }","duration":"155.365192ms","start":"2026-02-11T02:28:45.337671Z","end":"2026-02-11T02:28:45.493036Z","steps":["trace[140147471] 'read index received' (duration: 56.265748ms)","trace[140147471] 'applied index is now lower than readState.Index' (duration: 99.098634ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:28:45.493188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.496845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:28:45.493211Z","caller":"traceutil/trace.go:171","msg":"trace[931161252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:767; }","duration":"155.637738ms","start":"2026-02-11T02:28:45.337566Z","end":"2026-02-11T02:28:45.493203Z","steps":["trace[931161252] 'agreement among raft nodes before linearized reading' (duration: 155.559366ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:28:45.493483Z","caller":"traceutil/trace.go:171","msg":"trace[415854142] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"186.706002ms","start":"2026-02-11T02:28:45.306767Z","end":"2026-02-11T02:28:45.493473Z","steps":["trace[415854142] 'process raft request' (duration: 186.20376ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:28:45.493678Z","caller":"traceutil/trace.go:171","msg":"trace[794285176] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"186.976629ms","start":"2026-02-11T02:28:45.306691Z","end":"2026-02-11T02:28:45.493668Z","steps":["trace[794285176] 'process raft request' (duration: 186.151788ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:28:45.493938Z","caller":"traceutil/trace.go:171","msg":"trace[2079456321] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"187.095672ms","start":"2026-02-11T02:28:45.306835Z","end":"2026-02-11T02:28:45.493931Z","steps":["trace[2079456321] 'process raft request' (duration: 186.16768ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:29:05.816842Z","caller":"traceutil/trace.go:171","msg":"trace[605987605] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"213.8577ms","start":"2026-02-11T02:29:05.602957Z","end":"2026-02-11T02:29:05.816815Z","steps":["trace[605987605] 'process raft request' (duration: 213.522582ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:29:05.816636Z","caller":"traceutil/trace.go:171","msg":"trace[1982282637] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:915; }","duration":"103.568141ms","start":"2026-02-11T02:29:05.713051Z","end":"2026-02-11T02:29:05.816619Z","steps":["trace[1982282637] 'read index received' (duration: 103.362616ms)","trace[1982282637] 'applied index is now lower than readState.Index' (duration: 204.845µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:29:05.845759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.697288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-11T02:29:05.845827Z","caller":"traceutil/trace.go:171","msg":"trace[1900846528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:881; }","duration":"132.77077ms","start":"2026-02-11T02:29:05.713012Z","end":"2026-02-11T02:29:05.845783Z","steps":["trace[1900846528] 'agreement among raft nodes before linearized reading' (duration: 104.115675ms)","trace[1900846528] 'get authentication metadata' (duration: 28.561543ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:29:05.987308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.933329ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:29:05.98746Z","caller":"traceutil/trace.go:171","msg":"trace[1375578472] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:916; }","duration":"100.207689ms","start":"2026-02-11T02:29:05.887239Z","end":"2026-02-11T02:29:05.987446Z","steps":["trace[1375578472] 'read index received' (duration: 99.346308ms)","trace[1375578472] 'applied index is now lower than readState.Index' (duration: 860.761µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:29:05.987584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.342442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-11T02:29:05.987607Z","caller":"traceutil/trace.go:171","msg":"trace[1654396967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:884; }","duration":"100.390743ms","start":"2026-02-11T02:29:05.88721Z","end":"2026-02-11T02:29:05.9876Z","steps":["trace[1654396967] 'agreement among raft nodes before linearized reading' (duration: 100.277831ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:29:05.987791Z","caller":"traceutil/trace.go:171","msg":"trace[1084667024] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"138.22133ms","start":"2026-02-11T02:29:05.849562Z","end":"2026-02-11T02:29:05.987783Z","steps":["trace[1084667024] 'process raft request' (duration: 28.744128ms)","trace[1084667024] 'compare' (duration: 108.789575ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:29:05.987874Z","caller":"traceutil/trace.go:171","msg":"trace[1021100420] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"138.214801ms","start":"2026-02-11T02:29:05.849654Z","end":"2026-02-11T02:29:05.987868Z","steps":["trace[1021100420] 'process raft request' (duration: 137.727428ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:29:05.987965Z","caller":"traceutil/trace.go:171","msg":"trace[261809653] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"137.862492ms","start":"2026-02-11T02:29:05.850095Z","end":"2026-02-11T02:29:05.987957Z","steps":["trace[261809653] 'process raft request' (duration: 137.330278ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:29:06.468266Z","caller":"traceutil/trace.go:171","msg":"trace[810554336] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:919; }","duration":"448.709864ms","start":"2026-02-11T02:29:06.019138Z","end":"2026-02-11T02:29:06.467848Z","steps":["trace[810554336] 'read index received' (duration: 368.939084ms)","trace[810554336] 'applied index is now lower than readState.Index' (duration: 79.76954ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:29:06.468713Z","caller":"traceutil/trace.go:171","msg":"trace[1759103812] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"475.618039ms","start":"2026-02-11T02:29:05.993073Z","end":"2026-02-11T02:29:06.468691Z","steps":["trace[1759103812] 'process raft request' (duration: 394.969918ms)","trace[1759103812] 'compare' (duration: 79.349411ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:29:06.468915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:05.993055Z","time spent":"475.695431ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:29:06.468993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.853311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:29:06.469014Z","caller":"traceutil/trace.go:171","msg":"trace[150795429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:885; }","duration":"449.899163ms","start":"2026-02-11T02:29:06.019107Z","end":"2026-02-11T02:29:06.469006Z","steps":["trace[150795429] 'agreement among raft nodes before linearized reading' (duration: 449.867912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:06.469032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:06.019093Z","time spent":"449.935003ms","remote":"127.0.0.1:41704","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-11T02:29:07.244432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.16218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:29:07.244506Z","caller":"traceutil/trace.go:171","msg":"trace[1326941572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:885; }","duration":"169.254393ms","start":"2026-02-11T02:29:07.075236Z","end":"2026-02-11T02:29:07.244491Z","steps":["trace[1326941572] 'range keys from in-memory index tree' (duration: 169.099099ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:29:07.970833Z","caller":"traceutil/trace.go:171","msg":"trace[1392955802] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"111.62927ms","start":"2026-02-11T02:29:07.85917Z","end":"2026-02-11T02:29:07.970799Z","steps":["trace[1392955802] 'process raft request' (duration: 111.040206ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:29:08.251726Z","caller":"traceutil/trace.go:171","msg":"trace[290882338] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:921; }","duration":"235.090951ms","start":"2026-02-11T02:29:08.016616Z","end":"2026-02-11T02:29:08.251707Z","steps":["trace[290882338] 'read index received' (duration: 231.445353ms)","trace[290882338] 'applied index is now lower than readState.Index' (duration: 3.644878ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:29:08.251848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.219934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:29:08.251878Z","caller":"traceutil/trace.go:171","msg":"trace[1304427568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:889; }","duration":"235.280035ms","start":"2026-02-11T02:29:08.01659Z","end":"2026-02-11T02:29:08.25187Z","steps":["trace[1304427568] 'agreement among raft nodes before linearized reading' (duration: 235.195833ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:29:08.254286Z","caller":"traceutil/trace.go:171","msg":"trace[1759085023] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"259.629288ms","start":"2026-02-11T02:29:07.994645Z","end":"2026-02-11T02:29:08.254274Z","steps":["trace[1759085023] 'process raft request' (duration: 253.408589ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:29:08.254523Z","caller":"traceutil/trace.go:171","msg":"trace[438283137] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"257.991729ms","start":"2026-02-11T02:29:07.996521Z","end":"2026-02-11T02:29:08.254512Z","steps":["trace[438283137] 'process raft request' (duration: 255.10993ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:29:08.254692Z","caller":"traceutil/trace.go:171","msg":"trace[398933620] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"256.393031ms","start":"2026-02-11T02:29:07.998291Z","end":"2026-02-11T02:29:08.254684Z","steps":["trace[398933620] 'process raft request' (duration: 253.385429ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:29:12.566344Z","caller":"traceutil/trace.go:171","msg":"trace[2066421198] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:934; }","duration":"288.061877ms","start":"2026-02-11T02:29:12.278268Z","end":"2026-02-11T02:29:12.566329Z","steps":["trace[2066421198] 'read index received' (duration: 287.896663ms)","trace[2066421198] 'applied index is now lower than readState.Index' (duration: 164.684µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:29:12.566424Z","caller":"traceutil/trace.go:171","msg":"trace[1305646716] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"294.757837ms","start":"2026-02-11T02:29:12.271661Z","end":"2026-02-11T02:29:12.566418Z","steps":["trace[1305646716] 'process raft request' (duration: 294.555443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:12.566607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.352834ms","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-11T02:29:12.56665Z","caller":"traceutil/trace.go:171","msg":"trace[443383085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:899; }","duration":"283.424047ms","start":"2026-02-11T02:29:12.283215Z","end":"2026-02-11T02:29:12.566639Z","steps":["trace[443383085] 'agreement among raft nodes before linearized reading' (duration: 283.303114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:12.566736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.469218ms","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-11T02:29:12.566747Z","caller":"traceutil/trace.go:171","msg":"trace[289778657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:899; }","duration":"288.505758ms","start":"2026-02-11T02:29:12.278238Z","end":"2026-02-11T02:29:12.566744Z","steps":["trace[289778657] 'agreement among raft nodes before linearized reading' (duration: 288.472037ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:29:13.528913Z","caller":"traceutil/trace.go:171","msg":"trace[1857698534] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"174.248782ms","start":"2026-02-11T02:29:13.354649Z","end":"2026-02-11T02:29:13.528897Z","steps":["trace[1857698534] 'process raft request' (duration: 174.15962ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:29:14.089356Z","caller":"traceutil/trace.go:171","msg":"trace[380672009] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"101.625744ms","start":"2026-02-11T02:29:13.987711Z","end":"2026-02-11T02:29:14.089337Z","steps":["trace[380672009] 'read index received' (duration: 62.827015ms)","trace[380672009] 'applied index is now lower than readState.Index' (duration: 38.797819ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:29:14.08952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.795778ms","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-11T02:29:14.089541Z","caller":"traceutil/trace.go:171","msg":"trace[1623035733] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:903; }","duration":"101.85354ms","start":"2026-02-11T02:29:13.987682Z","end":"2026-02-11T02:29:14.089536Z","steps":["trace[1623035733] 'agreement among raft nodes before linearized reading' (duration: 101.747117ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:29:14.880867Z","caller":"traceutil/trace.go:171","msg":"trace[1340930023] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:941; }","duration":"298.964259ms","start":"2026-02-11T02:29:14.581888Z","end":"2026-02-11T02:29:14.880852Z","steps":["trace[1340930023] 'read index received' (duration: 298.796215ms)","trace[1340930023] 'applied index is now lower than readState.Index' (duration: 167.534µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:29:14.880933Z","caller":"traceutil/trace.go:171","msg":"trace[286264028] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"307.527784ms","start":"2026-02-11T02:29:14.573401Z","end":"2026-02-11T02:29:14.880929Z","steps":["trace[286264028] 'process raft request' (duration: 307.317549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:14.881002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:14.57339Z","time spent":"307.558075ms","remote":"127.0.0.1:41986","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-11T02:29:14.881139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.249466ms","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-11T02:29:14.881154Z","caller":"traceutil/trace.go:171","msg":"trace[1433273282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:905; }","duration":"299.279186ms","start":"2026-02-11T02:29:14.58187Z","end":"2026-02-11T02:29:14.881149Z","steps":["trace[1433273282] 'agreement among raft nodes before linearized reading' (duration: 299.197434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:14.881216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.295287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-11T02:29:14.881227Z","caller":"traceutil/trace.go:171","msg":"trace[785003698] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:905; }","duration":"299.312447ms","start":"2026-02-11T02:29:14.581911Z","end":"2026-02-11T02:29:14.881224Z","steps":["trace[785003698] 'agreement among raft nodes before linearized reading' (duration: 299.279726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:14.881299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.500277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T02:29:14.881341Z","caller":"traceutil/trace.go:171","msg":"trace[1335663713] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:905; }","duration":"174.57772ms","start":"2026-02-11T02:29:14.706754Z","end":"2026-02-11T02:29:14.881332Z","steps":["trace[1335663713] 'agreement among raft nodes before linearized reading' (duration: 174.503818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:14.881363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.42962ms","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-11T02:29:14.881382Z","caller":"traceutil/trace.go:171","msg":"trace[1060094248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:905; }","duration":"299.452751ms","start":"2026-02-11T02:29:14.581923Z","end":"2026-02-11T02:29:14.881376Z","steps":["trace[1060094248] 'agreement among raft nodes before linearized reading' (duration: 299.413ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:29:16.735848Z","caller":"traceutil/trace.go:171","msg":"trace[1836351911] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"636.309526ms","start":"2026-02-11T02:29:16.099521Z","end":"2026-02-11T02:29:16.73583Z","steps":["trace[1836351911] 'process raft request' (duration: 636.04376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:16.73595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:16.09951Z","time spent":"636.389548ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:29:19.080555Z","caller":"traceutil/trace.go:171","msg":"trace[276102108] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:954; }","duration":"105.321701ms","start":"2026-02-11T02:29:18.975214Z","end":"2026-02-11T02:29:19.080535Z","steps":["trace[276102108] 'read index received' (duration: 31.664588ms)","trace[276102108] 'applied index is now lower than readState.Index' (duration: 73.656443ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:29:19.080638Z","caller":"traceutil/trace.go:171","msg":"trace[101837339] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"123.262282ms","start":"2026-02-11T02:29:18.95737Z","end":"2026-02-11T02:29:19.080632Z","steps":["trace[101837339] 'process raft request' (duration: 49.559007ms)","trace[101837339] 'compare' (duration: 73.5274ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:29:19.080811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.550923ms","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-11T02:29:19.080859Z","caller":"traceutil/trace.go:171","msg":"trace[935320344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:917; }","duration":"104.643436ms","start":"2026-02-11T02:29:18.976203Z","end":"2026-02-11T02:29:19.080847Z","steps":["trace[935320344] 'agreement among raft nodes before linearized reading' (duration: 104.518473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:19.080937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.71848ms","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-11T02:29:19.080956Z","caller":"traceutil/trace.go:171","msg":"trace[153815065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:917; }","duration":"105.770122ms","start":"2026-02-11T02:29:18.975181Z","end":"2026-02-11T02:29:19.080951Z","steps":["trace[153815065] 'agreement among raft nodes before linearized reading' (duration: 105.68992ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:29:19.246118Z","caller":"traceutil/trace.go:171","msg":"trace[121599445] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"102.036005ms","start":"2026-02-11T02:29:19.144066Z","end":"2026-02-11T02:29:19.246102Z","steps":["trace[121599445] 'process raft request' (duration: 39.28681ms)","trace[121599445] 'compare' (duration: 62.685464ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:29:19.723686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.110867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:29:19.723784Z","caller":"traceutil/trace.go:171","msg":"trace[1347825604] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"303.48649ms","start":"2026-02-11T02:29:19.420279Z","end":"2026-02-11T02:29:19.723766Z","steps":["trace[1347825604] 'process raft request' (duration: 119.223309ms)","trace[1347825604] 'compare' (duration: 183.991974ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:29:19.723843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:19.420261Z","time spent":"303.555052ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:29:20.529311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.055781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:29:20.529376Z","caller":"traceutil/trace.go:171","msg":"trace[676223226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:920; }","duration":"510.153824ms","start":"2026-02-11T02:29:20.019207Z","end":"2026-02-11T02:29:20.529361Z","steps":["trace[676223226] 'range keys from in-memory index tree' (duration: 510.00643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:20.529416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:20.019192Z","time spent":"510.212325ms","remote":"127.0.0.1:41716","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-11T02:29:21.027847Z","caller":"traceutil/trace.go:171","msg":"trace[1083685031] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"240.174328ms","start":"2026-02-11T02:29:20.787658Z","end":"2026-02-11T02:29:21.027833Z","steps":["trace[1083685031] 'process raft request' (duration: 239.857771ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:29:21.227024Z","caller":"traceutil/trace.go:171","msg":"trace[539014832] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"141.557131ms","start":"2026-02-11T02:29:21.085417Z","end":"2026-02-11T02:29:21.226974Z","steps":["trace[539014832] 'read index received' (duration: 57.022246ms)","trace[539014832] 'applied index is now lower than readState.Index' (duration: 84.534045ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:29:21.227151Z","caller":"traceutil/trace.go:171","msg":"trace[832943534] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"196.804785ms","start":"2026-02-11T02:29:21.03034Z","end":"2026-02-11T02:29:21.227144Z","steps":["trace[832943534] 'process raft request' (duration: 112.135467ms)","trace[832943534] 'compare' (duration: 84.195647ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:29:21.2273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.869949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-11T02:29:21.227317Z","caller":"traceutil/trace.go:171","msg":"trace[626042906] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:922; }","duration":"141.922561ms","start":"2026-02-11T02:29:21.085389Z","end":"2026-02-11T02:29:21.227312Z","steps":["trace[626042906] 'agreement among raft nodes before linearized reading' (duration: 141.844809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:21.752147Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493993005562107,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T02:29:22.72998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.983382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:29:22.731422Z","caller":"traceutil/trace.go:171","msg":"trace[1450310436] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:960; }","duration":"1.479742558s","start":"2026-02-11T02:29:21.251659Z","end":"2026-02-11T02:29:22.731402Z","steps":["trace[1450310436] 'read index received' (duration: 587.196568ms)","trace[1450310436] 'applied index is now lower than readState.Index' (duration: 892.544919ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:29:22.731821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.480125408s","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-11T02:29:22.731988Z","caller":"traceutil/trace.go:171","msg":"trace[1061709257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:924; }","duration":"1.480270041s","start":"2026-02-11T02:29:21.251636Z","end":"2026-02-11T02:29:22.731906Z","steps":["trace[1061709257] 'agreement among raft nodes before linearized reading' (duration: 1.479933842s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:22.73202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.926911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-11T02:29:22.732182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:21.251628Z","time spent":"1.480444625s","remote":"127.0.0.1:41986","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-11T02:29:22.732249Z","caller":"traceutil/trace.go:171","msg":"trace[978879385] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:924; }","duration":"877.30013ms","start":"2026-02-11T02:29:21.854935Z","end":"2026-02-11T02:29:22.732235Z","steps":["trace[978879385] 'agreement among raft nodes before linearized reading' (duration: 876.884291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:22.732479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:21.854921Z","time spent":"877.538256ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":97,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-11T02:29:22.733003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.480943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:29:22.73371Z","caller":"traceutil/trace.go:171","msg":"trace[164577625] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:924; }","duration":"277.21596ms","start":"2026-02-11T02:29:22.45648Z","end":"2026-02-11T02:29:22.733696Z","steps":["trace[164577625] 'agreement among raft nodes before linearized reading' (duration: 276.486613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:22.734082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.308484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:29:22.734387Z","caller":"traceutil/trace.go:171","msg":"trace[610161573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:924; }","duration":"718.635593ms","start":"2026-02-11T02:29:22.015738Z","end":"2026-02-11T02:29:22.734373Z","steps":["trace[610161573] 'agreement among raft nodes before linearized reading' (duration: 718.300804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:22.734539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:22.015726Z","time spent":"718.798946ms","remote":"127.0.0.1:41704","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-11T02:29:22.738866Z","caller":"traceutil/trace.go:171","msg":"trace[1121031427] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"1.508297772s","start":"2026-02-11T02:29:21.23054Z","end":"2026-02-11T02:29:22.738838Z","steps":["trace[1121031427] 'process raft request' (duration: 608.380082ms)","trace[1121031427] 'compare' (duration: 889.966698ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:29:22.739016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:21.230524Z","time spent":"1.508395644s","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:29:22.739295Z","caller":"traceutil/trace.go:171","msg":"trace[2099980709] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"1.508409213s","start":"2026-02-11T02:29:21.230879Z","end":"2026-02-11T02:29:22.739289Z","steps":["trace[2099980709] 'process raft request' (duration: 1.50045314s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:22.739335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:21.230864Z","time spent":"1.508452075s","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:29:22.739438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.930727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T02:29:22.739506Z","caller":"traceutil/trace.go:171","msg":"trace[1647726259] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:924; }","duration":"818.138951ms","start":"2026-02-11T02:29:21.921358Z","end":"2026-02-11T02:29:22.739497Z","steps":["trace[1647726259] 'agreement among raft nodes before linearized reading' (duration: 817.931316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:22.739556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:21.921349Z","time spent":"818.199803ms","remote":"127.0.0.1:41984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-02-11T02:29:23.089112Z","caller":"traceutil/trace.go:171","msg":"trace[1196413881] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"343.749158ms","start":"2026-02-11T02:29:22.745333Z","end":"2026-02-11T02:29:23.089082Z","steps":["trace[1196413881] 'process raft request' (duration: 296.406077ms)","trace[1196413881] 'compare' (duration: 46.732377ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:29:23.089305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:22.745319Z","time spent":"343.871961ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:29:23.371249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.090646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:29:23.37137Z","caller":"traceutil/trace.go:171","msg":"trace[119113302] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"141.204561ms","start":"2026-02-11T02:29:23.230149Z","end":"2026-02-11T02:29:23.371353Z","steps":["trace[119113302] 'read index received' (duration: 5.101884ms)","trace[119113302] 'applied index is now lower than readState.Index' (duration: 136.100157ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:29:23.3714Z","caller":"traceutil/trace.go:171","msg":"trace[507857935] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"273.62618ms","start":"2026-02-11T02:29:23.097742Z","end":"2026-02-11T02:29:23.371368Z","steps":["trace[507857935] 'process raft request' (duration: 137.581324ms)","trace[507857935] 'compare' (duration: 104.82428ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:29:23.371509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.350724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-11T02:29:23.371531Z","caller":"traceutil/trace.go:171","msg":"trace[1172077754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:926; }","duration":"141.438347ms","start":"2026-02-11T02:29:23.230084Z","end":"2026-02-11T02:29:23.371523Z","steps":["trace[1172077754] 'agreement among raft nodes before linearized reading' (duration: 141.305794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:23.867704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.41325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:29:23.867771Z","caller":"traceutil/trace.go:171","msg":"trace[787730887] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"492.420115ms","start":"2026-02-11T02:29:23.375339Z","end":"2026-02-11T02:29:23.867759Z","steps":["trace[787730887] 'process raft request' (duration: 81.915593ms)","trace[787730887] 'compare' (duration: 409.99908ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:29:23.867804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:23.375319Z","time spent":"492.470006ms","remote":"127.0.0.1:41986","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-11T02:29:24.702946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.374901ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-11T02:29:24.703061Z","caller":"traceutil/trace.go:171","msg":"trace[1174881568] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:966; }","duration":"668.356319ms","start":"2026-02-11T02:29:24.034684Z","end":"2026-02-11T02:29:24.703041Z","steps":["trace[1174881568] 'read index received' (duration: 6.806224ms)","trace[1174881568] 'applied index is now lower than readState.Index' (duration: 661.548255ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:29:24.703134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.439172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:29:24.703159Z","caller":"traceutil/trace.go:171","msg":"trace[2114963866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"668.495912ms","start":"2026-02-11T02:29:24.034654Z","end":"2026-02-11T02:29:24.70315Z","steps":["trace[2114963866] 'agreement among raft nodes before linearized reading' (duration: 668.438991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:24.703191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:24.034629Z","time spent":"668.554844ms","remote":"127.0.0.1:41704","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-11T02:29:24.757818Z","caller":"traceutil/trace.go:171","msg":"trace[1149894047] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"401.749388ms","start":"2026-02-11T02:29:24.356046Z","end":"2026-02-11T02:29:24.757795Z","steps":["trace[1149894047] 'process raft request' (duration: 401.38641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:24.75794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:24.356014Z","time spent":"401.861741ms","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5143,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:29:24.980107Z","caller":"traceutil/trace.go:171","msg":"trace[843739273] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"213.392758ms","start":"2026-02-11T02:29:24.766707Z","end":"2026-02-11T02:29:24.9801Z","steps":["trace[843739273] 'process raft request' (duration: 139.749538ms)","trace[843739273] 'compare' (duration: 73.274781ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:29:24.980016Z","caller":"traceutil/trace.go:171","msg":"trace[1269663825] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"213.180503ms","start":"2026-02-11T02:29:24.766821Z","end":"2026-02-11T02:29:24.980002Z","steps":["trace[1269663825] 'read index received' (duration: 139.592154ms)","trace[1269663825] 'applied index is now lower than readState.Index' (duration: 73.587749ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:29:24.980375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.541191ms","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-11T02:29:24.980392Z","caller":"traceutil/trace.go:171","msg":"trace[1512104328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:930; }","duration":"213.578903ms","start":"2026-02-11T02:29:24.766808Z","end":"2026-02-11T02:29:24.980387Z","steps":["trace[1512104328] 'agreement among raft nodes before linearized reading' (duration: 213.47352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:25.587351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.42038ms","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-11T02:29:25.587402Z","caller":"traceutil/trace.go:171","msg":"trace[2051875646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:931; }","duration":"489.515772ms","start":"2026-02-11T02:29:25.097875Z","end":"2026-02-11T02:29:25.587391Z","steps":["trace[2051875646] 'range keys from in-memory index tree' (duration: 489.310197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:25.587424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:25.097861Z","time spent":"489.557633ms","remote":"127.0.0.1:41986","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-11T02:29:25.587542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.848051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-11T02:29:25.587556Z","caller":"traceutil/trace.go:171","msg":"trace[902590600] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:931; }","duration":"197.887821ms","start":"2026-02-11T02:29:25.389663Z","end":"2026-02-11T02:29:25.587551Z","steps":["trace[902590600] 'range keys from in-memory index tree' (duration: 197.527133ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:29:25.986377Z","caller":"traceutil/trace.go:171","msg":"trace[1179195327] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"200.391422ms","start":"2026-02-11T02:29:25.78597Z","end":"2026-02-11T02:29:25.986361Z","steps":["trace[1179195327] 'read index received' (duration: 122.622601ms)","trace[1179195327] 'applied index is now lower than readState.Index' (duration: 77.767861ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:29:25.98645Z","caller":"traceutil/trace.go:171","msg":"trace[1844669705] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"391.15812ms","start":"2026-02-11T02:29:25.595286Z","end":"2026-02-11T02:29:25.986444Z","steps":["trace[1844669705] 'process raft request' (duration: 313.354958ms)","trace[1844669705] 'compare' (duration: 77.648438ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:29:25.986513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:25.595249Z","time spent":"391.214081ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:29:25.986511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.230152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-11T02:29:25.986534Z","caller":"traceutil/trace.go:171","msg":"trace[1277327784] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:933; }","duration":"115.287913ms","start":"2026-02-11T02:29:25.87124Z","end":"2026-02-11T02:29:25.986528Z","steps":["trace[1277327784] 'agreement among raft nodes before linearized reading' (duration: 115.211181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:25.986637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.667479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:29:25.98665Z","caller":"traceutil/trace.go:171","msg":"trace[1474396653] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:933; }","duration":"200.705709ms","start":"2026-02-11T02:29:25.78594Z","end":"2026-02-11T02:29:25.986646Z","steps":["trace[1474396653] 'agreement among raft nodes before linearized reading' (duration: 200.669008ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:29:26.26056Z","caller":"traceutil/trace.go:171","msg":"trace[489514587] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:972; }","duration":"185.136701ms","start":"2026-02-11T02:29:26.075409Z","end":"2026-02-11T02:29:26.260545Z","steps":["trace[489514587] 'read index received' (duration: 184.999757ms)","trace[489514587] 'applied index is now lower than readState.Index' (duration: 136.414µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:29:26.260723Z","caller":"traceutil/trace.go:171","msg":"trace[1151551814] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"271.595853ms","start":"2026-02-11T02:29:25.989122Z","end":"2026-02-11T02:29:26.260717Z","steps":["trace[1151551814] 'process raft request' (duration: 271.335417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:26.260835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.414137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:29:26.260851Z","caller":"traceutil/trace.go:171","msg":"trace[1448346528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"185.463329ms","start":"2026-02-11T02:29:26.075383Z","end":"2026-02-11T02:29:26.260847Z","steps":["trace[1448346528] 'agreement among raft nodes before linearized reading' (duration: 185.424398ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:29:30.43089Z","caller":"traceutil/trace.go:171","msg":"trace[2101026704] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"114.070254ms","start":"2026-02-11T02:29:30.316787Z","end":"2026-02-11T02:29:30.430857Z","steps":["trace[2101026704] 'process raft request' (duration: 113.873299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:41.642563Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493993005562352,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T02:29:42.143411Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493993005562352,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T02:29:42.484492Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.434783485s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T02:29:42.488705Z","caller":"traceutil/trace.go:171","msg":"trace[1568726431] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1081; }","duration":"1.346734024s","start":"2026-02-11T02:29:41.141949Z","end":"2026-02-11T02:29:42.488683Z","steps":["trace[1568726431] 'read index received' (duration: 1.346507679s)","trace[1568726431] 'applied index is now lower than readState.Index' (duration: 225.135µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:29:42.490703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213863872s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-02-11T02:29:42.491018Z","caller":"traceutil/trace.go:171","msg":"trace[1829688160] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1039; }","duration":"1.214133429s","start":"2026-02-11T02:29:41.27676Z","end":"2026-02-11T02:29:42.490893Z","steps":["trace[1829688160] 'agreement among raft nodes before linearized reading' (duration: 1.212342765s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:42.491226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:41.276742Z","time spent":"1.214393445s","remote":"127.0.0.1:41820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":644,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-11T02:29:42.49232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.350372172s","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-11T02:29:42.497885Z","caller":"traceutil/trace.go:171","msg":"trace[268297209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1039; }","duration":"1.355510267s","start":"2026-02-11T02:29:41.141899Z","end":"2026-02-11T02:29:42.497409Z","steps":["trace[268297209] 'agreement among raft nodes before linearized reading' (duration: 1.350333921s)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:29:42.492596Z","caller":"traceutil/trace.go:171","msg":"trace[373058051] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"1.442979854s","start":"2026-02-11T02:29:41.049599Z","end":"2026-02-11T02:29:42.492579Z","steps":["trace[373058051] 'process raft request' (duration: 1.438921125s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:42.492772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.968567ms","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":"warn","ts":"2026-02-11T02:29:42.49281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.553314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-11T02:29:42.498607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.787047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-11T02:29:42.499501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:41.14188Z","time spent":"1.357607448s","remote":"127.0.0.1:41986","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-11T02:29:42.501835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:41.049584Z","time spent":"1.452191598s","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:29:42.501865Z","caller":"traceutil/trace.go:171","msg":"trace[991818331] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1039; }","duration":"836.101229ms","start":"2026-02-11T02:29:41.665753Z","end":"2026-02-11T02:29:42.501854Z","steps":["trace[991818331] 'agreement among raft nodes before linearized reading' (duration: 826.951156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:42.510392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:41.665715Z","time spent":"844.654017ms","remote":"127.0.0.1:41986","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-11T02:29:42.510561Z","caller":"traceutil/trace.go:171","msg":"trace[1428192700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1039; }","duration":"491.324225ms","start":"2026-02-11T02:29:42.019224Z","end":"2026-02-11T02:29:42.510549Z","steps":["trace[1428192700] 'agreement among raft nodes before linearized reading' (duration: 473.567654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:42.510587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:42.019212Z","time spent":"491.369627ms","remote":"127.0.0.1:41704","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-11T02:29:42.510678Z","caller":"traceutil/trace.go:171","msg":"trace[2141666474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1039; }","duration":"459.897021ms","start":"2026-02-11T02:29:42.050775Z","end":"2026-02-11T02:29:42.510673Z","steps":["trace[2141666474] 'agreement among raft nodes before linearized reading' (duration: 441.683108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:29:42.510714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:29:42.050769Z","time spent":"459.939343ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-11T02:32:19.60066Z","caller":"traceutil/trace.go:171","msg":"trace[165293609] transaction","detail":"{read_only:false; response_revision:1761; number_of_response:1; }","duration":"154.315768ms","start":"2026-02-11T02:32:19.446318Z","end":"2026-02-11T02:32:19.600634Z","steps":["trace[165293609] 'process raft request' (duration: 154.263146ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:32:19.601544Z","caller":"traceutil/trace.go:171","msg":"trace[1632060899] transaction","detail":"{read_only:false; response_revision:1760; number_of_response:1; }","duration":"157.695077ms","start":"2026-02-11T02:32:19.44372Z","end":"2026-02-11T02:32:19.601415Z","steps":["trace[1632060899] 'process raft request' (duration: 57.010733ms)","trace[1632060899] 'compare' (duration: 99.631799ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:32:30.02541Z","caller":"traceutil/trace.go:171","msg":"trace[485294374] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"115.301395ms","start":"2026-02-11T02:32:29.910082Z","end":"2026-02-11T02:32:30.025383Z","steps":["trace[485294374] 'process raft request' (duration: 81.107646ms)","trace[485294374] 'compare' (duration: 33.641817ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:32:50.032831Z","caller":"traceutil/trace.go:171","msg":"trace[495482737] linearizableReadLoop","detail":"{readStateIndex:2002; appliedIndex:2001; }","duration":"120.959476ms","start":"2026-02-11T02:32:49.911852Z","end":"2026-02-11T02:32:50.032812Z","steps":["trace[495482737] 'read index received' (duration: 120.784652ms)","trace[495482737] 'applied index is now lower than readState.Index' (duration: 174.164µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:32:50.033381Z","caller":"traceutil/trace.go:171","msg":"trace[864355591] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"124.20798ms","start":"2026-02-11T02:32:49.909157Z","end":"2026-02-11T02:32:50.033365Z","steps":["trace[864355591] 'process raft request' (duration: 123.538725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:32:50.033915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.041222ms","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-11T02:32:50.033949Z","caller":"traceutil/trace.go:171","msg":"trace[715022136] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1920; }","duration":"122.112473ms","start":"2026-02-11T02:32:49.911828Z","end":"2026-02-11T02:32:50.033941Z","steps":["trace[715022136] 'agreement among raft nodes before linearized reading' (duration: 121.996541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:32:50.035737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.502143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/csi-c988a784a7df2bdf59f06a5f00cf2d8573ce7306f052f4c360a54afcbc0bf65f\" ","response":"range_response_count:1 size:653"} {"level":"info","ts":"2026-02-11T02:32:50.03593Z","caller":"traceutil/trace.go:171","msg":"trace[895724875] range","detail":"{range_begin:/registry/volumeattachments/csi-c988a784a7df2bdf59f06a5f00cf2d8573ce7306f052f4c360a54afcbc0bf65f; range_end:; response_count:1; response_revision:1920; }","duration":"116.671407ms","start":"2026-02-11T02:32:49.919215Z","end":"2026-02-11T02:32:50.035887Z","steps":["trace[895724875] 'agreement among raft nodes before linearized reading' (duration: 116.37617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:32:50.035705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.267155ms","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-11T02:32:50.036191Z","caller":"traceutil/trace.go:171","msg":"trace[869373384] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1920; }","duration":"122.794718ms","start":"2026-02-11T02:32:49.913386Z","end":"2026-02-11T02:32:50.036181Z","steps":["trace[869373384] 'agreement among raft nodes before linearized reading' (duration: 121.187341ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:32:53.322271Z","caller":"traceutil/trace.go:171","msg":"trace[1738871687] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"157.859034ms","start":"2026-02-11T02:32:53.164368Z","end":"2026-02-11T02:32:53.322227Z","steps":["trace[1738871687] 'process raft request' (duration: 157.675269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:32:53.654208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.301679ms","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:528"} {"level":"info","ts":"2026-02-11T02:32:53.654354Z","caller":"traceutil/trace.go:171","msg":"trace[425274047] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1936; }","duration":"289.457672ms","start":"2026-02-11T02:32:53.364835Z","end":"2026-02-11T02:32:53.654293Z","steps":["trace[425274047] 'range keys from in-memory index tree' (duration: 289.038663ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:32:57.826009Z","caller":"traceutil/trace.go:171","msg":"trace[810694387] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"114.608111ms","start":"2026-02-11T02:32:57.71137Z","end":"2026-02-11T02:32:57.825978Z","steps":["trace[810694387] 'process raft request' (duration: 114.230583ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:33:00.293077Z","caller":"traceutil/trace.go:171","msg":"trace[1191082528] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"108.043289ms","start":"2026-02-11T02:33:00.185009Z","end":"2026-02-11T02:33:00.293052Z","steps":["trace[1191082528] 'process raft request' (duration: 93.925685ms)","trace[1191082528] 'compare' (duration: 12.993988ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:33:03.936073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.555233ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-11T02:33:03.936178Z","caller":"traceutil/trace.go:171","msg":"trace[164876079] linearizableReadLoop","detail":"{readStateIndex:2072; appliedIndex:2071; }","duration":"253.102069ms","start":"2026-02-11T02:33:03.683056Z","end":"2026-02-11T02:33:03.936158Z","steps":["trace[164876079] 'read index received' (duration: 86.398923ms)","trace[164876079] 'applied index is now lower than readState.Index' (duration: 166.701496ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:33:03.93626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.188801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:33:03.936287Z","caller":"traceutil/trace.go:171","msg":"trace[1842677172] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1987; }","duration":"253.227122ms","start":"2026-02-11T02:33:03.683051Z","end":"2026-02-11T02:33:03.936278Z","steps":["trace[1842677172] 'agreement among raft nodes before linearized reading' (duration: 253.15897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:33:04.223929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.40005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:33:04.224007Z","caller":"traceutil/trace.go:171","msg":"trace[1437976341] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"222.460166ms","start":"2026-02-11T02:33:04.001534Z","end":"2026-02-11T02:33:04.223994Z","steps":["trace[1437976341] 'process raft request' (duration: 99.928713ms)","trace[1437976341] 'compare' (duration: 122.149764ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:33:04.226329Z","caller":"traceutil/trace.go:171","msg":"trace[706301955] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"223.4945ms","start":"2026-02-11T02:33:04.002815Z","end":"2026-02-11T02:33:04.22631Z","steps":["trace[706301955] 'process raft request' (duration: 223.407068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:33:04.809404Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493993005564849,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T02:33:05.315291Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493993005564849,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T02:33:05.35602Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.068109465s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T02:33:05.417386Z","caller":"traceutil/trace.go:171","msg":"trace[682087200] linearizableReadLoop","detail":"{readStateIndex:2077; appliedIndex:2075; }","duration":"1.108784179s","start":"2026-02-11T02:33:04.308579Z","end":"2026-02-11T02:33:05.417363Z","steps":["trace[682087200] 'read index received' (duration: 1.047616005s)","trace[682087200] 'applied index is now lower than readState.Index' (duration: 61.166783ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:33:05.417648Z","caller":"traceutil/trace.go:171","msg":"trace[2126602254] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"1.129806641s","start":"2026-02-11T02:33:04.287827Z","end":"2026-02-11T02:33:05.417634Z","steps":["trace[2126602254] 'process raft request' (duration: 1.06835008s)","trace[2126602254] 'compare' (duration: 61.035631ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:33:05.417754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:33:04.287806Z","time spent":"1.129876283s","remote":"127.0.0.1:41986","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-11T02:33:05.417926Z","caller":"traceutil/trace.go:171","msg":"trace[263408677] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"933.644889ms","start":"2026-02-11T02:33:04.484271Z","end":"2026-02-11T02:33:05.417916Z","steps":["trace[263408677] 'process raft request' (duration: 933.047986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:33:05.418244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109649208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-02-11T02:33:05.418401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:33:04.484248Z","time spent":"933.701791ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:33:05.419485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091264426s","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-11T02:33:05.41953Z","caller":"traceutil/trace.go:171","msg":"trace[1018207359] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1992; }","duration":"1.091333738s","start":"2026-02-11T02:33:04.328184Z","end":"2026-02-11T02:33:05.419517Z","steps":["trace[1018207359] 'agreement among raft nodes before linearized reading' (duration: 1.091190035s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:33:05.419563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:33:04.328172Z","time spent":"1.091382929s","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T02:33:05.41971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.496922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-11T02:33:05.41973Z","caller":"traceutil/trace.go:171","msg":"trace[1130099813] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1992; }","duration":"315.567083ms","start":"2026-02-11T02:33:05.104154Z","end":"2026-02-11T02:33:05.419722Z","steps":["trace[1130099813] 'agreement among raft nodes before linearized reading' (duration: 315.510752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:33:05.419746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:33:05.104136Z","time spent":"315.606184ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-11T02:33:05.419806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.074425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:33:05.41982Z","caller":"traceutil/trace.go:171","msg":"trace[1277099037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1992; }","duration":"401.125696ms","start":"2026-02-11T02:33:05.018689Z","end":"2026-02-11T02:33:05.419815Z","steps":["trace[1277099037] 'agreement among raft nodes before linearized reading' (duration: 401.104176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:33:05.419838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:33:05.018671Z","time spent":"401.160106ms","remote":"127.0.0.1:41704","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-11T02:33:05.419974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.589022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-11T02:33:05.419992Z","caller":"traceutil/trace.go:171","msg":"trace[510869158] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1992; }","duration":"511.707814ms","start":"2026-02-11T02:33:04.908278Z","end":"2026-02-11T02:33:05.419986Z","steps":["trace[510869158] 'agreement among raft nodes before linearized reading' (duration: 511.643492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:33:05.420007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:33:04.908259Z","time spent":"511.744375ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-11T02:33:05.420091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.527981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-02-11T02:33:05.420108Z","caller":"traceutil/trace.go:171","msg":"trace[620628222] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1992; }","duration":"718.578753ms","start":"2026-02-11T02:33:04.701525Z","end":"2026-02-11T02:33:05.420104Z","steps":["trace[620628222] 'agreement among raft nodes before linearized reading' (duration: 718.531832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:33:05.420123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:33:04.701507Z","time spent":"718.612603ms","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-11T02:33:05.4202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050719477s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-11T02:33:05.420215Z","caller":"traceutil/trace.go:171","msg":"trace[1874498701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1992; }","duration":"1.050754827s","start":"2026-02-11T02:33:04.369455Z","end":"2026-02-11T02:33:05.42021Z","steps":["trace[1874498701] 'agreement among raft nodes before linearized reading' (duration: 1.050711656s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:33:05.420229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:33:04.369444Z","time spent":"1.050781687s","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T02:33:05.420305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057689136s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-11T02:33:05.420339Z","caller":"traceutil/trace.go:171","msg":"trace[1212739597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1992; }","duration":"1.057766628s","start":"2026-02-11T02:33:04.362567Z","end":"2026-02-11T02:33:05.420315Z","steps":["trace[1212739597] 'agreement among raft nodes before linearized reading' (duration: 1.057706116s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:33:05.420355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:33:04.362544Z","time spent":"1.057807008s","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-11T02:33:05.418296Z","caller":"traceutil/trace.go:171","msg":"trace[529771533] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1992; }","duration":"1.10973467s","start":"2026-02-11T02:33:04.308547Z","end":"2026-02-11T02:33:05.418282Z","steps":["trace[529771533] 'agreement among raft nodes before linearized reading' (duration: 1.109564886s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:33:05.431417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:33:04.308535Z","time spent":"1.122753609s","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-11T02:33:09.660523Z","caller":"traceutil/trace.go:171","msg":"trace[1775725918] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"102.384129ms","start":"2026-02-11T02:33:09.558109Z","end":"2026-02-11T02:33:09.660493Z","steps":["trace[1775725918] 'process raft request' (duration: 43.975659ms)","trace[1775725918] 'compare' (duration: 56.67523ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:33:37.863978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.022299ms","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:121 size:92516"} {"level":"info","ts":"2026-02-11T02:33:37.864054Z","caller":"traceutil/trace.go:171","msg":"trace[464695686] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:121; response_revision:2150; }","duration":"108.118391ms","start":"2026-02-11T02:33:37.755919Z","end":"2026-02-11T02:33:37.864037Z","steps":["trace[464695686] 'range keys from in-memory index tree' (duration: 107.476335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:33:37.864049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.867371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T02:33:37.879202Z","caller":"traceutil/trace.go:171","msg":"trace[832627537] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2150; }","duration":"114.952804ms","start":"2026-02-11T02:33:37.749146Z","end":"2026-02-11T02:33:37.864099Z","steps":["trace[832627537] 'count revisions from in-memory index tree' (duration: 114.82822ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:33:47.907672Z","caller":"traceutil/trace.go:171","msg":"trace[1989046555] linearizableReadLoop","detail":"{readStateIndex:2288; appliedIndex:2287; }","duration":"130.190525ms","start":"2026-02-11T02:33:47.777445Z","end":"2026-02-11T02:33:47.907636Z","steps":["trace[1989046555] 'read index received' (duration: 55.950816ms)","trace[1989046555] 'applied index is now lower than readState.Index' (duration: 74.238349ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:33:47.908106Z","caller":"traceutil/trace.go:171","msg":"trace[615702071] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"157.41578ms","start":"2026-02-11T02:33:47.750633Z","end":"2026-02-11T02:33:47.908049Z","steps":["trace[615702071] 'process raft request' (duration: 82.915834ms)","trace[615702071] 'compare' (duration: 73.930502ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:33:47.908391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.926142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-11T02:33:47.908995Z","caller":"traceutil/trace.go:171","msg":"trace[280387678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2195; }","duration":"131.606788ms","start":"2026-02-11T02:33:47.777375Z","end":"2026-02-11T02:33:47.908982Z","steps":["trace[280387678] 'agreement among raft nodes before linearized reading' (duration: 130.82555ms)"],"step_count":1}