{"level":"warn","ts":"2026-02-06T20:03:07.766914Z","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-06T20:03:07.768043Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.217: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://162.253.55.217:2380","--initial-cluster=instance=https://162.253.55.217:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.217:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.217: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-06T20:03:07.768405Z","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-06T20:03:07.768577Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-02-06T20:03:07.768671Z","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-06T20:03:07.770823Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"]} {"level":"info","ts":"2026-02-06T20:03:07.770943Z","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://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.217: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-06T20:03:07.784046Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"12.673872ms"} {"level":"info","ts":"2026-02-06T20:03:07.807184Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e368e6818bd6f7","cluster-id":"289e52865b7eaeb1"} {"level":"info","ts":"2026-02-06T20:03:07.807313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-06T20:03:07.807362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 0"} {"level":"info","ts":"2026-02-06T20:03:07.807391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e368e6818bd6f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-06T20:03:07.807412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 1"} {"level":"info","ts":"2026-02-06T20:03:07.807476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"warn","ts":"2026-02-06T20:03:07.823259Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-06T20:03:07.829766Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-06T20:03:07.836135Z","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-06T20:03:07.846148Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e368e6818bd6f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-06T20:03:07.846858Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e368e6818bd6f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-06T20:03:07.846986Z","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-06T20:03:07.847035Z","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-06T20:03:07.847054Z","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-06T20:03:07.847413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"info","ts":"2026-02-06T20:03:07.847627Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","added-peer-id":"f1e368e6818bd6f7","added-peer-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-02-06T20:03:07.86483Z","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-06T20:03:07.865096Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e368e6818bd6f7","initial-advertise-peer-urls":["https://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-06T20:03:07.865172Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-06T20:03:07.865311Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-02-06T20:03:07.86538Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-02-06T20:03:08.411245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-06T20:03:08.411365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-06T20:03:08.411393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgPreVoteResp from f1e368e6818bd6f7 at term 1"} {"level":"info","ts":"2026-02-06T20:03:08.411417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became candidate at term 2"} {"level":"info","ts":"2026-02-06T20:03:08.411441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgVoteResp from f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-02-06T20:03:08.411468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became leader at term 2"} {"level":"info","ts":"2026-02-06T20:03:08.411487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e368e6818bd6f7 elected leader f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-02-06T20:03:08.492101Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T20:03:08.492389Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e368e6818bd6f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.217:2379]}","request-path":"/0/members/f1e368e6818bd6f7/attributes","cluster-id":"289e52865b7eaeb1","publish-timeout":"7s"} {"level":"info","ts":"2026-02-06T20:03:08.492438Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T20:03:08.492461Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T20:03:08.498215Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.217:2379"} {"level":"info","ts":"2026-02-06T20:03:08.49265Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-06T20:03:08.498342Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-06T20:03:08.499556Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-06T20:03:08.51527Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T20:03:08.515343Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T20:03:08.515369Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T20:03:11.033591Z","caller":"traceutil/trace.go:171","msg":"trace[2070299310] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"164.895251ms","start":"2026-02-06T20:03:10.868051Z","end":"2026-02-06T20:03:11.032946Z","steps":["trace[2070299310] 'process raft request' (duration: 164.84545ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:03:11.034309Z","caller":"traceutil/trace.go:171","msg":"trace[1941866118] transaction","detail":"{read_only:false; response_revision:64; number_of_response:1; }","duration":"168.931974ms","start":"2026-02-06T20:03:10.865363Z","end":"2026-02-06T20:03:11.034295Z","steps":["trace[1941866118] 'process raft request' (duration: 78.47407ms)","trace[1941866118] 'compare' (duration: 88.917704ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:03:11.324703Z","caller":"traceutil/trace.go:171","msg":"trace[655513734] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"196.033391ms","start":"2026-02-06T20:03:11.128639Z","end":"2026-02-06T20:03:11.324673Z","steps":["trace[655513734] 'process raft request' (duration: 195.974569ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:03:11.325275Z","caller":"traceutil/trace.go:171","msg":"trace[1184973378] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"212.230981ms","start":"2026-02-06T20:03:11.113029Z","end":"2026-02-06T20:03:11.32526Z","steps":["trace[1184973378] 'process raft request' (duration: 155.848622ms)","trace[1184973378] 'compare' (duration: 55.585809ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:03:11.611476Z","caller":"traceutil/trace.go:171","msg":"trace[1490093571] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"229.140131ms","start":"2026-02-06T20:03:11.382242Z","end":"2026-02-06T20:03:11.611382Z","steps":["trace[1490093571] 'process raft request' (duration: 218.976583ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:03:11.946091Z","caller":"traceutil/trace.go:171","msg":"trace[1637453639] transaction","detail":"{read_only:false; response_revision:81; number_of_response:1; }","duration":"109.376082ms","start":"2026-02-06T20:03:11.836679Z","end":"2026-02-06T20:03:11.946055Z","steps":["trace[1637453639] 'process raft request' (duration: 31.297844ms)","trace[1637453639] 'compare' (duration: 77.904814ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:03:12.151041Z","caller":"traceutil/trace.go:171","msg":"trace[817524200] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"106.907962ms","start":"2026-02-06T20:03:12.044098Z","end":"2026-02-06T20:03:12.151006Z","steps":["trace[817524200] 'process raft request' (duration: 18.648833ms)","trace[817524200] 'compare' (duration: 87.93594ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:03:12.281399Z","caller":"traceutil/trace.go:171","msg":"trace[922871601] linearizableReadLoop","detail":"{readStateIndex:89; appliedIndex:88; }","duration":"125.450421ms","start":"2026-02-06T20:03:12.155919Z","end":"2026-02-06T20:03:12.28137Z","steps":["trace[922871601] 'read index received' (duration: 106.371277ms)","trace[922871601] 'applied index is now lower than readState.Index' (duration: 19.078164ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:03:12.281681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.649016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T20:03:12.281803Z","caller":"traceutil/trace.go:171","msg":"trace[1998882717] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:85; }","duration":"125.916133ms","start":"2026-02-06T20:03:12.15587Z","end":"2026-02-06T20:03:12.281787Z","steps":["trace[1998882717] 'agreement among raft nodes before linearized reading' (duration: 125.619445ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:03:12.282128Z","caller":"traceutil/trace.go:171","msg":"trace[182324716] transaction","detail":"{read_only:false; response_revision:85; number_of_response:1; }","duration":"128.679323ms","start":"2026-02-06T20:03:12.153406Z","end":"2026-02-06T20:03:12.282085Z","steps":["trace[182324716] 'process raft request' (duration: 108.959163ms)","trace[182324716] 'compare' (duration: 18.863699ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:03:12.487706Z","caller":"traceutil/trace.go:171","msg":"trace[292472604] transaction","detail":"{read_only:false; response_revision:88; number_of_response:1; }","duration":"137.728171ms","start":"2026-02-06T20:03:12.34995Z","end":"2026-02-06T20:03:12.487678Z","steps":["trace[292472604] 'process raft request' (duration: 136.677564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:12.921098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.831183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-06T20:03:12.921709Z","caller":"traceutil/trace.go:171","msg":"trace[631384981] linearizableReadLoop","detail":"{readStateIndex:94; appliedIndex:93; }","duration":"280.80766ms","start":"2026-02-06T20:03:12.640809Z","end":"2026-02-06T20:03:12.921616Z","steps":["trace[631384981] 'read index received' (duration: 2.220727ms)","trace[631384981] 'applied index is now lower than readState.Index' (duration: 278.583963ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:03:12.92191Z","caller":"traceutil/trace.go:171","msg":"trace[40681178] transaction","detail":"{read_only:false; response_revision:90; number_of_response:1; }","duration":"375.029628ms","start":"2026-02-06T20:03:12.546863Z","end":"2026-02-06T20:03:12.921893Z","steps":["trace[40681178] 'process raft request' (duration: 96.24533ms)","trace[40681178] 'compare' (duration: 275.656488ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:03:12.921998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:12.546846Z","time spent":"375.115779ms","remote":"127.0.0.1:57386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-06T20:03:12.923101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.290636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T20:03:12.92316Z","caller":"traceutil/trace.go:171","msg":"trace[217862318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:90; }","duration":"282.385289ms","start":"2026-02-06T20:03:12.640748Z","end":"2026-02-06T20:03:12.923133Z","steps":["trace[217862318] 'agreement among raft nodes before linearized reading' (duration: 281.736272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:12.924092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.569331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-06T20:03:12.924334Z","caller":"traceutil/trace.go:171","msg":"trace[1282156851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:90; }","duration":"188.829928ms","start":"2026-02-06T20:03:12.735456Z","end":"2026-02-06T20:03:12.924286Z","steps":["trace[1282156851] 'agreement among raft nodes before linearized reading' (duration: 188.386465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:12.925236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.360115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:430"} {"level":"info","ts":"2026-02-06T20:03:12.925512Z","caller":"traceutil/trace.go:171","msg":"trace[635964467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:90; }","duration":"188.57431ms","start":"2026-02-06T20:03:12.736847Z","end":"2026-02-06T20:03:12.925421Z","steps":["trace[635964467] 'agreement among raft nodes before linearized reading' (duration: 188.320444ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:03:13.285627Z","caller":"traceutil/trace.go:171","msg":"trace[901605272] transaction","detail":"{read_only:false; response_revision:92; number_of_response:1; }","duration":"341.397345ms","start":"2026-02-06T20:03:12.944195Z","end":"2026-02-06T20:03:13.285592Z","steps":["trace[901605272] 'process raft request' (duration: 340.812909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:13.285809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:12.944189Z","time spent":"341.542608ms","remote":"127.0.0.1:57386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T20:03:13.285603Z","caller":"traceutil/trace.go:171","msg":"trace[1587495604] transaction","detail":"{read_only:false; response_revision:93; number_of_response:1; }","duration":"341.272802ms","start":"2026-02-06T20:03:12.944272Z","end":"2026-02-06T20:03:13.285545Z","steps":["trace[1587495604] 'process raft request' (duration: 341.144779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:13.286445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:12.944267Z","time spent":"341.903037ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:03:13.731355Z","caller":"traceutil/trace.go:171","msg":"trace[422553114] transaction","detail":"{read_only:false; response_revision:99; number_of_response:1; }","duration":"153.838101ms","start":"2026-02-06T20:03:13.577466Z","end":"2026-02-06T20:03:13.731304Z","steps":["trace[422553114] 'process raft request' (duration: 138.426729ms)","trace[422553114] 'compare' (duration: 14.918079ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:03:13.731636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.326002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:persistent-volume-provisioner\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T20:03:13.731692Z","caller":"traceutil/trace.go:171","msg":"trace[939532979] range","detail":"{range_begin:/registry/clusterroles/system:persistent-volume-provisioner; range_end:; response_count:0; response_revision:99; }","duration":"150.436955ms","start":"2026-02-06T20:03:13.581243Z","end":"2026-02-06T20:03:13.73168Z","steps":["trace[939532979] 'agreement among raft nodes before linearized reading' (duration: 150.209189ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:03:13.731403Z","caller":"traceutil/trace.go:171","msg":"trace[872384187] linearizableReadLoop","detail":"{readStateIndex:103; appliedIndex:102; }","duration":"150.094867ms","start":"2026-02-06T20:03:13.581287Z","end":"2026-02-06T20:03:13.731382Z","steps":["trace[872384187] 'read index received' (duration: 134.616054ms)","trace[872384187] 'applied index is now lower than readState.Index' (duration: 15.477393ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:03:13.963052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.270256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-06T20:03:13.96319Z","caller":"traceutil/trace.go:171","msg":"trace[484724452] linearizableReadLoop","detail":"{readStateIndex:109; appliedIndex:108; }","duration":"131.232676ms","start":"2026-02-06T20:03:13.831935Z","end":"2026-02-06T20:03:13.963168Z","steps":["trace[484724452] 'read index received' (duration: 16.722054ms)","trace[484724452] 'applied index is now lower than readState.Index' (duration: 114.509082ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:03:13.963324Z","caller":"traceutil/trace.go:171","msg":"trace[1845381844] transaction","detail":"{read_only:false; response_revision:104; number_of_response:1; }","duration":"134.996784ms","start":"2026-02-06T20:03:13.828314Z","end":"2026-02-06T20:03:13.963311Z","steps":["trace[1845381844] 'process raft request' (duration: 20.369238ms)","trace[1845381844] 'compare' (duration: 114.101531ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:03:13.964165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.201131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T20:03:13.964225Z","caller":"traceutil/trace.go:171","msg":"trace[2003067655] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient; range_end:; response_count:0; response_revision:104; }","duration":"132.310034ms","start":"2026-02-06T20:03:13.8319Z","end":"2026-02-06T20:03:13.96421Z","steps":["trace[2003067655] 'agreement among raft nodes before linearized reading' (duration: 132.207712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:14.202645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.260597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-06T20:03:14.202836Z","caller":"traceutil/trace.go:171","msg":"trace[445872283] transaction","detail":"{read_only:false; response_revision:105; number_of_response:1; }","duration":"232.099835ms","start":"2026-02-06T20:03:13.970683Z","end":"2026-02-06T20:03:14.202783Z","steps":["trace[445872283] 'process raft request' (duration: 119.553621ms)","trace[445872283] 'compare' (duration: 112.076562ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:03:14.203124Z","caller":"traceutil/trace.go:171","msg":"trace[1478360140] transaction","detail":"{read_only:false; response_revision:106; number_of_response:1; }","duration":"232.007252ms","start":"2026-02-06T20:03:13.971097Z","end":"2026-02-06T20:03:14.203104Z","steps":["trace[1478360140] 'process raft request' (duration: 231.91737ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:03:14.440324Z","caller":"traceutil/trace.go:171","msg":"trace[460704307] linearizableReadLoop","detail":"{readStateIndex:119; appliedIndex:118; }","duration":"157.666126ms","start":"2026-02-06T20:03:14.282633Z","end":"2026-02-06T20:03:14.440299Z","steps":["trace[460704307] 'read index received' (duration: 75.142044ms)","trace[460704307] 'applied index is now lower than readState.Index' (duration: 82.522842ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:03:14.440474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.81529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T20:03:14.440505Z","caller":"traceutil/trace.go:171","msg":"trace[398981835] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver; range_end:; response_count:0; response_revision:114; }","duration":"157.865311ms","start":"2026-02-06T20:03:14.28263Z","end":"2026-02-06T20:03:14.440496Z","steps":["trace[398981835] 'agreement among raft nodes before linearized reading' (duration: 157.760859ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:03:14.440693Z","caller":"traceutil/trace.go:171","msg":"trace[1484200571] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"158.818126ms","start":"2026-02-06T20:03:14.281861Z","end":"2026-02-06T20:03:14.440679Z","steps":["trace[1484200571] 'process raft request' (duration: 75.935515ms)","trace[1484200571] 'compare' (duration: 82.103251ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:03:14.611812Z","caller":"traceutil/trace.go:171","msg":"trace[513573384] linearizableReadLoop","detail":"{readStateIndex:121; appliedIndex:120; }","duration":"108.829439ms","start":"2026-02-06T20:03:14.502958Z","end":"2026-02-06T20:03:14.611788Z","steps":["trace[513573384] 'read index received' (duration: 38.753712ms)","trace[513573384] 'applied index is now lower than readState.Index' (duration: 70.074617ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:03:14.611923Z","caller":"traceutil/trace.go:171","msg":"trace[1573331949] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"109.101146ms","start":"2026-02-06T20:03:14.50281Z","end":"2026-02-06T20:03:14.611911Z","steps":["trace[1573331949] 'process raft request' (duration: 38.948938ms)","trace[1573331949] 'compare' (duration: 69.865301ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:03:14.612165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.188468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:service-account-issuer-discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T20:03:14.612198Z","caller":"traceutil/trace.go:171","msg":"trace[1895272822] range","detail":"{range_begin:/registry/clusterroles/system:service-account-issuer-discovery; range_end:; response_count:0; response_revision:116; }","duration":"109.2616ms","start":"2026-02-06T20:03:14.502927Z","end":"2026-02-06T20:03:14.612188Z","steps":["trace[1895272822] 'agreement among raft nodes before linearized reading' (duration: 109.168628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:21.879292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.786413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-06T20:03:21.879432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.241334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:03:21.879471Z","caller":"traceutil/trace.go:171","msg":"trace[578382401] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:346; }","duration":"401.318496ms","start":"2026-02-06T20:03:21.478139Z","end":"2026-02-06T20:03:21.879458Z","steps":["trace[578382401] 'range keys from in-memory index tree' (duration: 401.144662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:21.879531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:21.478114Z","time spent":"401.392498ms","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-02-06T20:03:21.883501Z","caller":"traceutil/trace.go:171","msg":"trace[135175333] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:346; }","duration":"232.928456ms","start":"2026-02-06T20:03:21.646435Z","end":"2026-02-06T20:03:21.879363Z","steps":["trace[135175333] 'range keys from in-memory index tree' (duration: 232.67459ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:03:32.342955Z","caller":"traceutil/trace.go:171","msg":"trace[1818868686] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:477; }","duration":"334.756917ms","start":"2026-02-06T20:03:32.008169Z","end":"2026-02-06T20:03:32.342926Z","steps":["trace[1818868686] 'read index received' (duration: 334.569612ms)","trace[1818868686] 'applied index is now lower than readState.Index' (duration: 186.675µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:03:32.343085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.895952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:03:32.343104Z","caller":"traceutil/trace.go:171","msg":"trace[1781317861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:464; }","duration":"334.963293ms","start":"2026-02-06T20:03:32.008135Z","end":"2026-02-06T20:03:32.343098Z","steps":["trace[1781317861] 'agreement among raft nodes before linearized reading' (duration: 334.8866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:32.343129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:32.00812Z","time spent":"335.002285ms","remote":"127.0.0.1:57044","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-06T20:03:32.343409Z","caller":"traceutil/trace.go:171","msg":"trace[153035248] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"405.211835ms","start":"2026-02-06T20:03:31.938192Z","end":"2026-02-06T20:03:32.343404Z","steps":["trace[153035248] 'process raft request' (duration: 404.598808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:32.343471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:31.938174Z","time spent":"405.251435ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:03:45.162916Z","caller":"traceutil/trace.go:171","msg":"trace[1836675860] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"111.852686ms","start":"2026-02-06T20:03:45.051044Z","end":"2026-02-06T20:03:45.162897Z","steps":["trace[1836675860] 'process raft request' (duration: 111.738563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:45.756469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.753923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-06T20:03:45.756534Z","caller":"traceutil/trace.go:171","msg":"trace[1244662335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:526; }","duration":"544.868066ms","start":"2026-02-06T20:03:45.211653Z","end":"2026-02-06T20:03:45.756521Z","steps":["trace[1244662335] 'range keys from in-memory index tree' (duration: 544.633499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:45.756559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:45.211634Z","time spent":"544.919776ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T20:03:45.756793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.811886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.217\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-06T20:03:45.75681Z","caller":"traceutil/trace.go:171","msg":"trace[607636771] range","detail":"{range_begin:/registry/masterleases/162.253.55.217; range_end:; response_count:1; response_revision:526; }","duration":"313.867028ms","start":"2026-02-06T20:03:45.442936Z","end":"2026-02-06T20:03:45.756803Z","steps":["trace[607636771] 'range keys from in-memory index tree' (duration: 313.696193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:45.756823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:45.44291Z","time spent":"313.909199ms","remote":"127.0.0.1:57058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.217\" "} {"level":"warn","ts":"2026-02-06T20:03:45.75692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.431734ms","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-06T20:03:45.756935Z","caller":"traceutil/trace.go:171","msg":"trace[1784371906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:526; }","duration":"264.475596ms","start":"2026-02-06T20:03:45.492456Z","end":"2026-02-06T20:03:45.756931Z","steps":["trace[1784371906] 'range keys from in-memory index tree' (duration: 264.300972ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:03:48.027803Z","caller":"traceutil/trace.go:171","msg":"trace[84007341] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"247.26841ms","start":"2026-02-06T20:03:47.780503Z","end":"2026-02-06T20:03:48.027771Z","steps":["trace[84007341] 'process raft request' (duration: 247.125426ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:03:48.079559Z","caller":"traceutil/trace.go:171","msg":"trace[2068370024] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"297.995686ms","start":"2026-02-06T20:03:47.781546Z","end":"2026-02-06T20:03:48.079542Z","steps":["trace[2068370024] 'process raft request' (duration: 297.575785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:48.973806Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021192883479433,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T20:03:49.219646Z","caller":"traceutil/trace.go:171","msg":"trace[1481478270] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"746.19861ms","start":"2026-02-06T20:03:48.473431Z","end":"2026-02-06T20:03:49.21963Z","steps":["trace[1481478270] 'read index received' (duration: 746.029546ms)","trace[1481478270] 'applied index is now lower than readState.Index' (duration: 168.284µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:03:49.2199Z","caller":"traceutil/trace.go:171","msg":"trace[980594512] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"863.063783ms","start":"2026-02-06T20:03:48.356829Z","end":"2026-02-06T20:03:49.219893Z","steps":["trace[980594512] 'process raft request' (duration: 862.698524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:49.219977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:48.356811Z","time spent":"863.109684ms","remote":"127.0.0.1:57302","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-06T20:03:49.22011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.761573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:03:49.220189Z","caller":"traceutil/trace.go:171","msg":"trace[1633039299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"208.850485ms","start":"2026-02-06T20:03:49.011313Z","end":"2026-02-06T20:03:49.220164Z","steps":["trace[1633039299] 'agreement among raft nodes before linearized reading' (duration: 208.704091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:49.22023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.803845ms","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-06T20:03:49.220264Z","caller":"traceutil/trace.go:171","msg":"trace[279607451] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:534; }","duration":"746.860257ms","start":"2026-02-06T20:03:48.473393Z","end":"2026-02-06T20:03:49.220253Z","steps":["trace[279607451] 'agreement among raft nodes before linearized reading' (duration: 746.772995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:49.220282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:48.473378Z","time spent":"746.900498ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-06T20:03:49.496456Z","caller":"traceutil/trace.go:171","msg":"trace[124134051] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"269.553045ms","start":"2026-02-06T20:03:49.226872Z","end":"2026-02-06T20:03:49.496425Z","steps":["trace[124134051] 'process raft request' (duration: 236.118807ms)","trace[124134051] 'compare' (duration: 33.13284ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:03:49.530794Z","caller":"traceutil/trace.go:171","msg":"trace[1938835556] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"302.709776ms","start":"2026-02-06T20:03:49.22806Z","end":"2026-02-06T20:03:49.530769Z","steps":["trace[1938835556] 'process raft request' (duration: 302.598053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:49.530947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:49.228051Z","time spent":"302.813918ms","remote":"127.0.0.1:57302","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-06T20:03:51.253128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.551741ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-06T20:03:51.253214Z","caller":"traceutil/trace.go:171","msg":"trace[857327800] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:556; }","duration":"248.394188ms","start":"2026-02-06T20:03:51.004804Z","end":"2026-02-06T20:03:51.253198Z","steps":["trace[857327800] 'read index received' (duration: 44.722264ms)","trace[857327800] 'applied index is now lower than readState.Index' (duration: 203.670814ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:03:51.253281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.46756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:03:51.253299Z","caller":"traceutil/trace.go:171","msg":"trace[1500884930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"248.532082ms","start":"2026-02-06T20:03:51.004761Z","end":"2026-02-06T20:03:51.253293Z","steps":["trace[1500884930] 'agreement among raft nodes before linearized reading' (duration: 248.47766ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:03:52.121783Z","caller":"traceutil/trace.go:171","msg":"trace[1524053697] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:559; }","duration":"114.265098ms","start":"2026-02-06T20:03:52.007498Z","end":"2026-02-06T20:03:52.121763Z","steps":["trace[1524053697] 'read index received' (duration: 114.081953ms)","trace[1524053697] 'applied index is now lower than readState.Index' (duration: 182.585µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:03:52.121954Z","caller":"traceutil/trace.go:171","msg":"trace[825347598] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"290.766623ms","start":"2026-02-06T20:03:51.831179Z","end":"2026-02-06T20:03:52.121946Z","steps":["trace[825347598] 'process raft request' (duration: 290.458065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:52.122089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.578085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:03:52.122109Z","caller":"traceutil/trace.go:171","msg":"trace[1896710953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:541; }","duration":"114.654927ms","start":"2026-02-06T20:03:52.007447Z","end":"2026-02-06T20:03:52.122102Z","steps":["trace[1896710953] 'agreement among raft nodes before linearized reading' (duration: 114.611606ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:03:54.556069Z","caller":"traceutil/trace.go:171","msg":"trace[1414061513] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"357.70659ms","start":"2026-02-06T20:03:54.198335Z","end":"2026-02-06T20:03:54.556042Z","steps":["trace[1414061513] 'process raft request' (duration: 357.591117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:54.556192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:54.19832Z","time spent":"357.808973ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:03:54.701737Z","caller":"traceutil/trace.go:171","msg":"trace[248667089] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"502.607425ms","start":"2026-02-06T20:03:54.19911Z","end":"2026-02-06T20:03:54.701718Z","steps":["trace[248667089] 'process raft request' (duration: 502.182274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:54.701855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:54.199102Z","time spent":"502.689427ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:03:57.350787Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021192883479515,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T20:03:57.385889Z","caller":"traceutil/trace.go:171","msg":"trace[757562174] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"536.03485ms","start":"2026-02-06T20:03:56.849815Z","end":"2026-02-06T20:03:57.38585Z","steps":["trace[757562174] 'process raft request' (duration: 530.365767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:57.386021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:56.849804Z","time spent":"536.156224ms","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T20:03:57.395173Z","caller":"traceutil/trace.go:171","msg":"trace[1055430197] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:591; }","duration":"544.860994ms","start":"2026-02-06T20:03:56.850297Z","end":"2026-02-06T20:03:57.395158Z","steps":["trace[1055430197] 'read index received' (duration: 529.899415ms)","trace[1055430197] 'applied index is now lower than readState.Index' (duration: 14.960199ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:03:57.39526Z","caller":"traceutil/trace.go:171","msg":"trace[62631156] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"527.149176ms","start":"2026-02-06T20:03:56.868104Z","end":"2026-02-06T20:03:57.395253Z","steps":["trace[62631156] 'process raft request' (duration: 526.977722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:57.395309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:56.868094Z","time spent":"527.189177ms","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-06T20:03:57.395451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.069297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:03:57.395482Z","caller":"traceutil/trace.go:171","msg":"trace[245839854] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:573; }","duration":"554.13421ms","start":"2026-02-06T20:03:56.841338Z","end":"2026-02-06T20:03:57.395473Z","steps":["trace[245839854] 'agreement among raft nodes before linearized reading' (duration: 554.034267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:57.395508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:56.841334Z","time spent":"554.166ms","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-06T20:03:57.395575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.361276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:03:57.395598Z","caller":"traceutil/trace.go:171","msg":"trace[1281537479] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:573; }","duration":"556.392687ms","start":"2026-02-06T20:03:56.839199Z","end":"2026-02-06T20:03:57.395592Z","steps":["trace[1281537479] 'agreement among raft nodes before linearized reading' (duration: 556.360466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:57.39562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:56.839194Z","time spent":"556.421527ms","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-06T20:03:57.395681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.412468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:03:57.395703Z","caller":"traceutil/trace.go:171","msg":"trace[1994192680] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:573; }","duration":"558.443979ms","start":"2026-02-06T20:03:56.837253Z","end":"2026-02-06T20:03:57.395697Z","steps":["trace[1994192680] 'agreement among raft nodes before linearized reading' (duration: 558.411548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:57.395722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:56.837248Z","time spent":"558.47045ms","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-06T20:03:57.395777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.40849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:03:57.39581Z","caller":"traceutil/trace.go:171","msg":"trace[802467825] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:573; }","duration":"564.445462ms","start":"2026-02-06T20:03:56.831358Z","end":"2026-02-06T20:03:57.395804Z","steps":["trace[802467825] 'agreement among raft nodes before linearized reading' (duration: 564.4056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:57.395832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:56.831356Z","time spent":"564.471582ms","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-06T20:03:57.400839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.748511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:03:57.400991Z","caller":"traceutil/trace.go:171","msg":"trace[501648818] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:575; }","duration":"540.930405ms","start":"2026-02-06T20:03:56.860033Z","end":"2026-02-06T20:03:57.400963Z","steps":["trace[501648818] 'agreement among raft nodes before linearized reading' (duration: 540.72295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:57.401068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:56.860024Z","time spent":"541.035167ms","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-06T20:03:57.401568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.432557ms","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-06T20:03:57.401708Z","caller":"traceutil/trace.go:171","msg":"trace[1171941782] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:576; }","duration":"182.624261ms","start":"2026-02-06T20:03:57.219076Z","end":"2026-02-06T20:03:57.401701Z","steps":["trace[1171941782] 'agreement among raft nodes before linearized reading' (duration: 182.451587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:57.401899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.798971ms","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-06T20:03:57.401968Z","caller":"traceutil/trace.go:171","msg":"trace[787761285] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:576; }","duration":"383.888423ms","start":"2026-02-06T20:03:57.018073Z","end":"2026-02-06T20:03:57.401961Z","steps":["trace[787761285] 'agreement among raft nodes before linearized reading' (duration: 383.805961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:57.402049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:57.018067Z","time spent":"383.975676ms","remote":"127.0.0.1:57086","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":"warn","ts":"2026-02-06T20:03:57.402194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.34057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:03:57.402265Z","caller":"traceutil/trace.go:171","msg":"trace[1577906029] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"396.435082ms","start":"2026-02-06T20:03:57.005824Z","end":"2026-02-06T20:03:57.402259Z","steps":["trace[1577906029] 'agreement among raft nodes before linearized reading' (duration: 396.34708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:57.402363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:57.005808Z","time spent":"396.532294ms","remote":"127.0.0.1:57050","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-06T20:03:57.402528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.926841ms","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-06T20:03:57.402639Z","caller":"traceutil/trace.go:171","msg":"trace[1090106968] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:576; }","duration":"542.025003ms","start":"2026-02-06T20:03:56.860594Z","end":"2026-02-06T20:03:57.402619Z","steps":["trace[1090106968] 'agreement among raft nodes before linearized reading' (duration: 541.916021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:57.402709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:03:56.860574Z","time spent":"542.128227ms","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-02-06T20:03:58.900927Z","caller":"traceutil/trace.go:171","msg":"trace[365591621] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"220.45242ms","start":"2026-02-06T20:03:58.680418Z","end":"2026-02-06T20:03:58.90087Z","steps":["trace[365591621] 'read index received' (duration: 220.131552ms)","trace[365591621] 'applied index is now lower than readState.Index' (duration: 318.888µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:03:58.901207Z","caller":"traceutil/trace.go:171","msg":"trace[1950810783] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"281.503089ms","start":"2026-02-06T20:03:58.619689Z","end":"2026-02-06T20:03:58.901192Z","steps":["trace[1950810783] 'process raft request' (duration: 280.904844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:58.901636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.207239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-l9hn5\" ","response":"range_response_count:1 size:17415"} {"level":"info","ts":"2026-02-06T20:03:58.9017Z","caller":"traceutil/trace.go:171","msg":"trace[1332857767] range","detail":"{range_begin:/registry/pods/kube-system/cilium-l9hn5; range_end:; response_count:1; response_revision:605; }","duration":"221.315721ms","start":"2026-02-06T20:03:58.680367Z","end":"2026-02-06T20:03:58.901683Z","steps":["trace[1332857767] 'agreement among raft nodes before linearized reading' (duration: 221.086765ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:03:58.977118Z","caller":"traceutil/trace.go:171","msg":"trace[1268145425] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"292.155158ms","start":"2026-02-06T20:03:58.684923Z","end":"2026-02-06T20:03:58.977078Z","steps":["trace[1268145425] 'process raft request' (duration: 291.718687ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:03:58.977642Z","caller":"traceutil/trace.go:171","msg":"trace[328490364] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"149.464279ms","start":"2026-02-06T20:03:58.828166Z","end":"2026-02-06T20:03:58.97763Z","steps":["trace[328490364] 'process raft request' (duration: 148.864584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:58.977672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.59752ms","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-06T20:03:58.977836Z","caller":"traceutil/trace.go:171","msg":"trace[672034494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"251.833215ms","start":"2026-02-06T20:03:58.725941Z","end":"2026-02-06T20:03:58.977774Z","steps":["trace[672034494] 'agreement among raft nodes before linearized reading' (duration: 251.306242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:03:58.978286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.046647ms","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-06T20:03:58.978341Z","caller":"traceutil/trace.go:171","msg":"trace[316377077] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:607; }","duration":"115.1629ms","start":"2026-02-06T20:03:58.863165Z","end":"2026-02-06T20:03:58.978328Z","steps":["trace[316377077] 'agreement among raft nodes before linearized reading' (duration: 114.912624ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:04:08.930612Z","caller":"traceutil/trace.go:171","msg":"trace[1588697725] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"166.144703ms","start":"2026-02-06T20:04:08.764448Z","end":"2026-02-06T20:04:08.930593Z","steps":["trace[1588697725] 'process raft request' (duration: 166.01664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:09.905946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.629145ms","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-06T20:04:09.906118Z","caller":"traceutil/trace.go:171","msg":"trace[1943863294] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:685; }","duration":"498.82264ms","start":"2026-02-06T20:04:09.407271Z","end":"2026-02-06T20:04:09.906093Z","steps":["trace[1943863294] 'range keys from in-memory index tree' (duration: 498.600014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:09.90613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.780313ms","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-06T20:04:09.906214Z","caller":"traceutil/trace.go:171","msg":"trace[1501120402] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:685; }","duration":"807.933827ms","start":"2026-02-06T20:04:09.098261Z","end":"2026-02-06T20:04:09.906195Z","steps":["trace[1501120402] 'range keys from in-memory index tree' (duration: 807.563608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:09.906251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:09.098235Z","time spent":"808.008059ms","remote":"127.0.0.1:57302","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-06T20:04:09.906445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.061157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-06T20:04:09.906465Z","caller":"traceutil/trace.go:171","msg":"trace[648392870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:685; }","duration":"778.13805ms","start":"2026-02-06T20:04:09.12832Z","end":"2026-02-06T20:04:09.906458Z","steps":["trace[648392870] 'range keys from in-memory index tree' (duration: 777.944074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:09.906482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:09.128293Z","time spent":"778.18511ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-06T20:04:09.934127Z","caller":"traceutil/trace.go:171","msg":"trace[1561105367] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"146.572936ms","start":"2026-02-06T20:04:09.787529Z","end":"2026-02-06T20:04:09.934102Z","steps":["trace[1561105367] 'read index received' (duration: 146.454323ms)","trace[1561105367] 'applied index is now lower than readState.Index' (duration: 117.222µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:04:09.934556Z","caller":"traceutil/trace.go:171","msg":"trace[35644195] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"221.667484ms","start":"2026-02-06T20:04:09.712869Z","end":"2026-02-06T20:04:09.934536Z","steps":["trace[35644195] 'process raft request' (duration: 221.069399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:09.934837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.286394ms","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-06T20:04:09.935029Z","caller":"traceutil/trace.go:171","msg":"trace[1718170191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:686; }","duration":"147.487489ms","start":"2026-02-06T20:04:09.787515Z","end":"2026-02-06T20:04:09.935002Z","steps":["trace[1718170191] 'agreement among raft nodes before linearized reading' (duration: 147.020606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:10.434816Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021192883479747,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T20:04:10.934928Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021192883479747,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T20:04:11.435957Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021192883479747,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T20:04:11.927417Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.912790973s","expected-duration":"1s"} {"level":"info","ts":"2026-02-06T20:04:11.928696Z","caller":"traceutil/trace.go:171","msg":"trace[1265392726] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"2.011298686s","start":"2026-02-06T20:04:09.917369Z","end":"2026-02-06T20:04:11.928668Z","steps":["trace[1265392726] 'process raft request' (duration: 2.011031989s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:11.929022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:09.917345Z","time spent":"2.011514632s","remote":"127.0.0.1:57302","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-06T20:04:11.930096Z","caller":"traceutil/trace.go:171","msg":"trace[881204610] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"2.014565239s","start":"2026-02-06T20:04:09.915486Z","end":"2026-02-06T20:04:11.930052Z","steps":["trace[881204610] 'process raft request' (duration: 2.012680741s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:11.930327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:09.915467Z","time spent":"2.014717023s","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:04:12.017608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000273745s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-06T20:04:12.017714Z","caller":"traceutil/trace.go:171","msg":"trace[2094499782] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00044842s","start":"2026-02-06T20:04:10.017239Z","end":"2026-02-06T20:04:12.017688Z","steps":["trace[2094499782] 'agreement among raft nodes before linearized reading' (duration: 2.000305876s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:12.017812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:10.017226Z","time spent":"2.000533852s","remote":"127.0.0.1:57044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/06 20:04:12 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-06T20:04:12.158651Z","caller":"traceutil/trace.go:171","msg":"trace[1349744992] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"2.2164321s","start":"2026-02-06T20:04:09.942182Z","end":"2026-02-06T20:04:12.158615Z","steps":["trace[1349744992] 'process raft request' (duration: 2.197375346s)","trace[1349744992] 'compare' (duration: 18.609353ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:04:12.158826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:09.942164Z","time spent":"2.216563124s","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:04:12.162649Z","caller":"traceutil/trace.go:171","msg":"trace[149129388] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:710; }","duration":"2.228115077s","start":"2026-02-06T20:04:09.934502Z","end":"2026-02-06T20:04:12.162618Z","steps":["trace[149129388] 'read index received' (duration: 1.993591116s)","trace[149129388] 'applied index is now lower than readState.Index' (duration: 234.522271ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:04:12.1629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.362342089s","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-06T20:04:12.162934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.902964042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T20:04:12.162953Z","caller":"traceutil/trace.go:171","msg":"trace[866436928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:689; }","duration":"2.362425631s","start":"2026-02-06T20:04:09.800509Z","end":"2026-02-06T20:04:12.162935Z","steps":["trace[866436928] 'agreement among raft nodes before linearized reading' (duration: 2.362273007s)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:04:12.162978Z","caller":"traceutil/trace.go:171","msg":"trace[275654147] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:689; }","duration":"1.903084805s","start":"2026-02-06T20:04:10.259881Z","end":"2026-02-06T20:04:12.162966Z","steps":["trace[275654147] 'agreement among raft nodes before linearized reading' (duration: 1.902915051s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:12.162991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:09.800493Z","time spent":"2.362490422s","remote":"127.0.0.1:57302","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-06T20:04:12.16302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:10.259859Z","time spent":"1.903150716s","remote":"127.0.0.1:57386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":66,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-02-06T20:04:12.163292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119094066s","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-06T20:04:12.16333Z","caller":"traceutil/trace.go:171","msg":"trace[1492782584] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:689; }","duration":"1.119172228s","start":"2026-02-06T20:04:11.044146Z","end":"2026-02-06T20:04:12.163318Z","steps":["trace[1492782584] 'agreement among raft nodes before linearized reading' (duration: 1.119108136s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:12.163401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:11.044124Z","time spent":"1.119267641s","remote":"127.0.0.1:57198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-06T20:04:12.163445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.228275622s","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-06T20:04:12.163481Z","caller":"traceutil/trace.go:171","msg":"trace[2071213065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:689; }","duration":"1.228327893s","start":"2026-02-06T20:04:10.935143Z","end":"2026-02-06T20:04:12.163471Z","steps":["trace[2071213065] 'agreement among raft nodes before linearized reading' (duration: 1.228147678s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:12.16351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:10.935067Z","time spent":"1.228436935s","remote":"127.0.0.1:57302","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-06T20:04:12.163592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.673933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:04:12.163623Z","caller":"traceutil/trace.go:171","msg":"trace[1798525992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"145.748375ms","start":"2026-02-06T20:04:12.017865Z","end":"2026-02-06T20:04:12.163613Z","steps":["trace[1798525992] 'agreement among raft nodes before linearized reading' (duration: 145.696404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:12.163985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.602297729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T20:04:12.164037Z","caller":"traceutil/trace.go:171","msg":"trace[1433879143] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:689; }","duration":"1.602388021s","start":"2026-02-06T20:04:10.561637Z","end":"2026-02-06T20:04:12.164025Z","steps":["trace[1433879143] 'agreement among raft nodes before linearized reading' (duration: 1.602307069s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:12.164068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:10.561615Z","time spent":"1.602443933s","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-06T20:04:14.165588Z","caller":"traceutil/trace.go:171","msg":"trace[431949411] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:727; }","duration":"346.691262ms","start":"2026-02-06T20:04:13.818865Z","end":"2026-02-06T20:04:14.165556Z","steps":["trace[431949411] 'read index received' (duration: 345.83923ms)","trace[431949411] 'applied index is now lower than readState.Index' (duration: 851.122µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:04:14.165717Z","caller":"traceutil/trace.go:171","msg":"trace[729776145] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"403.499947ms","start":"2026-02-06T20:04:13.762209Z","end":"2026-02-06T20:04:14.165709Z","steps":["trace[729776145] 'process raft request' (duration: 402.512472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:14.16597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.763301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-06T20:04:14.166036Z","caller":"traceutil/trace.go:171","msg":"trace[1977338352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:703; }","duration":"229.875543ms","start":"2026-02-06T20:04:13.936143Z","end":"2026-02-06T20:04:14.166018Z","steps":["trace[1977338352] 'agreement among raft nodes before linearized reading' (duration: 229.680208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:14.166222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.35837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T20:04:14.166241Z","caller":"traceutil/trace.go:171","msg":"trace[1925796331] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:703; }","duration":"347.40498ms","start":"2026-02-06T20:04:13.81883Z","end":"2026-02-06T20:04:14.166235Z","steps":["trace[1925796331] 'agreement among raft nodes before linearized reading' (duration: 347.363009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:14.166266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:13.818798Z","time spent":"347.459482ms","remote":"127.0.0.1:57494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-06T20:04:14.166666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.466734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:04:14.166686Z","caller":"traceutil/trace.go:171","msg":"trace[1207698913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:703; }","duration":"159.507845ms","start":"2026-02-06T20:04:14.007171Z","end":"2026-02-06T20:04:14.166679Z","steps":["trace[1207698913] 'agreement among raft nodes before linearized reading' (duration: 159.478704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:14.166816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.628552ms","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-06T20:04:14.166834Z","caller":"traceutil/trace.go:171","msg":"trace[1768746757] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:703; }","duration":"230.655262ms","start":"2026-02-06T20:04:13.936174Z","end":"2026-02-06T20:04:14.166829Z","steps":["trace[1768746757] 'agreement among raft nodes before linearized reading' (duration: 230.591531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:14.169381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:13.762195Z","time spent":"403.539058ms","remote":"127.0.0.1:57228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5008,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:04:14.410332Z","caller":"traceutil/trace.go:171","msg":"trace[1412999136] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"160.549331ms","start":"2026-02-06T20:04:14.249762Z","end":"2026-02-06T20:04:14.410311Z","steps":["trace[1412999136] 'process raft request' (duration: 160.327295ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:04:14.414244Z","caller":"traceutil/trace.go:171","msg":"trace[1948942551] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:739; }","duration":"162.628975ms","start":"2026-02-06T20:04:14.251589Z","end":"2026-02-06T20:04:14.414218Z","steps":["trace[1948942551] 'read index received' (duration: 158.440439ms)","trace[1948942551] 'applied index is now lower than readState.Index' (duration: 4.187776ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:04:14.414283Z","caller":"traceutil/trace.go:171","msg":"trace[1372947636] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"164.193044ms","start":"2026-02-06T20:04:14.250081Z","end":"2026-02-06T20:04:14.414274Z","steps":["trace[1372947636] 'process raft request' (duration: 163.992148ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:04:14.41449Z","caller":"traceutil/trace.go:171","msg":"trace[1563272087] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"164.313117ms","start":"2026-02-06T20:04:14.250169Z","end":"2026-02-06T20:04:14.414482Z","steps":["trace[1563272087] 'process raft request' (duration: 164.010709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:14.414663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.045926ms","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-06T20:04:14.414691Z","caller":"traceutil/trace.go:171","msg":"trace[816180887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:717; }","duration":"163.105697ms","start":"2026-02-06T20:04:14.251578Z","end":"2026-02-06T20:04:14.414684Z","steps":["trace[816180887] 'agreement among raft nodes before linearized reading' (duration: 162.979264ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:04:18.553588Z","caller":"traceutil/trace.go:171","msg":"trace[381759603] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"340.061164ms","start":"2026-02-06T20:04:18.213477Z","end":"2026-02-06T20:04:18.553538Z","steps":["trace[381759603] 'read index received' (duration: 339.898129ms)","trace[381759603] 'applied index is now lower than readState.Index' (duration: 161.695µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:04:18.553731Z","caller":"traceutil/trace.go:171","msg":"trace[411356966] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"345.986234ms","start":"2026-02-06T20:04:18.207734Z","end":"2026-02-06T20:04:18.55372Z","steps":["trace[411356966] 'process raft request' (duration: 345.622885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:18.553885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:18.207709Z","time spent":"346.052536ms","remote":"127.0.0.1:57302","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-06T20:04:18.554102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.599098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-06T20:04:18.554191Z","caller":"traceutil/trace.go:171","msg":"trace[1501391179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:739; }","duration":"340.74177ms","start":"2026-02-06T20:04:18.213431Z","end":"2026-02-06T20:04:18.554173Z","steps":["trace[1501391179] 'agreement among raft nodes before linearized reading' (duration: 340.450683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:18.554237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:18.213419Z","time spent":"340.811033ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-06T20:04:18.935843Z","caller":"traceutil/trace.go:171","msg":"trace[1825048863] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:767; }","duration":"382.148395ms","start":"2026-02-06T20:04:18.553683Z","end":"2026-02-06T20:04:18.935831Z","steps":["trace[1825048863] 'read index received' (duration: 382.140665ms)","trace[1825048863] 'applied index is now lower than readState.Index' (duration: 6.43µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:04:18.935775Z","caller":"traceutil/trace.go:171","msg":"trace[1978630971] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"433.310405ms","start":"2026-02-06T20:04:18.50244Z","end":"2026-02-06T20:04:18.93575Z","steps":["trace[1978630971] 'process raft request' (duration: 433.127909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:18.936231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.648202ms","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-06T20:04:18.936311Z","caller":"traceutil/trace.go:171","msg":"trace[886865525] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:740; }","duration":"715.769334ms","start":"2026-02-06T20:04:18.220524Z","end":"2026-02-06T20:04:18.936293Z","steps":["trace[886865525] 'agreement among raft nodes before linearized reading' (duration: 715.489667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:18.936354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:18.220495Z","time spent":"715.848927ms","remote":"127.0.0.1:57302","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-06T20:04:18.936417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.747154ms","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-06T20:04:18.936462Z","caller":"traceutil/trace.go:171","msg":"trace[328722119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:740; }","duration":"453.821826ms","start":"2026-02-06T20:04:18.482617Z","end":"2026-02-06T20:04:18.936439Z","steps":["trace[328722119] 'agreement among raft nodes before linearized reading' (duration: 453.685383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:18.936486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:18.482592Z","time spent":"453.885767ms","remote":"127.0.0.1:57302","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-06T20:04:18.936635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.685269ms","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-06T20:04:18.936658Z","caller":"traceutil/trace.go:171","msg":"trace[58379134] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:740; }","duration":"683.7291ms","start":"2026-02-06T20:04:18.252922Z","end":"2026-02-06T20:04:18.936651Z","steps":["trace[58379134] 'agreement among raft nodes before linearized reading' (duration: 683.644378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:18.936677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:18.252903Z","time spent":"683.769771ms","remote":"127.0.0.1:57302","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-06T20:04:18.936258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:18.502428Z","time spent":"433.743855ms","remote":"127.0.0.1:57226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4769,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:04:18.941891Z","caller":"traceutil/trace.go:171","msg":"trace[185983109] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"383.651022ms","start":"2026-02-06T20:04:18.558228Z","end":"2026-02-06T20:04:18.941879Z","steps":["trace[185983109] 'process raft request' (duration: 383.55303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:18.941981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:18.55821Z","time spent":"383.713324ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:04:21.455999Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021192883479850,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T20:04:21.617159Z","caller":"traceutil/trace.go:171","msg":"trace[1609219960] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:773; }","duration":"661.33869ms","start":"2026-02-06T20:04:20.955787Z","end":"2026-02-06T20:04:21.617126Z","steps":["trace[1609219960] 'read index received' (duration: 587.128654ms)","trace[1609219960] 'applied index is now lower than readState.Index' (duration: 74.207956ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:04:21.617479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.581787ms","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-06T20:04:21.617503Z","caller":"traceutil/trace.go:171","msg":"trace[1423603765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:746; }","duration":"661.657578ms","start":"2026-02-06T20:04:20.955838Z","end":"2026-02-06T20:04:21.617495Z","steps":["trace[1423603765] 'agreement among raft nodes before linearized reading' (duration: 661.461323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:21.617527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:20.955832Z","time spent":"661.689349ms","remote":"127.0.0.1:57302","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-06T20:04:21.617529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.69749ms","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-06T20:04:21.617625Z","caller":"traceutil/trace.go:171","msg":"trace[737428954] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:746; }","duration":"661.872004ms","start":"2026-02-06T20:04:20.955729Z","end":"2026-02-06T20:04:21.617601Z","steps":["trace[737428954] 'agreement among raft nodes before linearized reading' (duration: 661.470754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:21.617705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:20.955701Z","time spent":"661.983027ms","remote":"127.0.0.1:57302","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-06T20:04:21.617866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.603297ms","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-06T20:04:21.617883Z","caller":"traceutil/trace.go:171","msg":"trace[646952360] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:746; }","duration":"661.669469ms","start":"2026-02-06T20:04:20.956208Z","end":"2026-02-06T20:04:21.617878Z","steps":["trace[646952360] 'agreement among raft nodes before linearized reading' (duration: 661.612607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:21.617895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:20.956183Z","time spent":"661.709539ms","remote":"127.0.0.1:57302","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-06T20:04:21.617964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.062589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-06T20:04:21.617978Z","caller":"traceutil/trace.go:171","msg":"trace[1768102400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:746; }","duration":"662.110099ms","start":"2026-02-06T20:04:20.955864Z","end":"2026-02-06T20:04:21.617974Z","steps":["trace[1768102400] 'agreement among raft nodes before linearized reading' (duration: 662.070188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:21.61799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:20.95586Z","time spent":"662.127421ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T20:04:22.714122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.677402ms","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-06T20:04:22.71424Z","caller":"traceutil/trace.go:171","msg":"trace[572760709] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:751; }","duration":"121.852217ms","start":"2026-02-06T20:04:22.592369Z","end":"2026-02-06T20:04:22.714221Z","steps":["trace[572760709] 'agreement among raft nodes before linearized reading' (duration: 121.452227ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:04:22.713377Z","caller":"traceutil/trace.go:171","msg":"trace[1359463924] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"120.955104ms","start":"2026-02-06T20:04:22.59239Z","end":"2026-02-06T20:04:22.713345Z","steps":["trace[1359463924] 'read index received' (duration: 120.692748ms)","trace[1359463924] 'applied index is now lower than readState.Index' (duration: 261.226µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:04:22.715638Z","caller":"traceutil/trace.go:171","msg":"trace[547040612] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"127.483841ms","start":"2026-02-06T20:04:22.588125Z","end":"2026-02-06T20:04:22.715609Z","steps":["trace[547040612] 'process raft request' (duration: 125.031618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:22.716868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.403451ms","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-06T20:04:22.716958Z","caller":"traceutil/trace.go:171","msg":"trace[1404101581] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:751; }","duration":"124.506464ms","start":"2026-02-06T20:04:22.592422Z","end":"2026-02-06T20:04:22.716928Z","steps":["trace[1404101581] 'agreement among raft nodes before linearized reading' (duration: 124.36065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:22.721668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.08122ms","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-06T20:04:22.721707Z","caller":"traceutil/trace.go:171","msg":"trace[1868799035] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:751; }","duration":"114.135292ms","start":"2026-02-06T20:04:22.607564Z","end":"2026-02-06T20:04:22.721699Z","steps":["trace[1868799035] 'agreement among raft nodes before linearized reading' (duration: 114.07013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:22.721831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.001413ms","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-06T20:04:22.721853Z","caller":"traceutil/trace.go:171","msg":"trace[1338760336] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:751; }","duration":"102.031633ms","start":"2026-02-06T20:04:22.619814Z","end":"2026-02-06T20:04:22.721846Z","steps":["trace[1338760336] 'agreement among raft nodes before linearized reading' (duration: 101.990132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:22.722115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.393963ms","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-06T20:04:22.72213Z","caller":"traceutil/trace.go:171","msg":"trace[1206659402] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:751; }","duration":"102.422813ms","start":"2026-02-06T20:04:22.619701Z","end":"2026-02-06T20:04:22.722124Z","steps":["trace[1206659402] 'agreement among raft nodes before linearized reading' (duration: 102.195688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:22.722167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.606054ms","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-06T20:04:22.722196Z","caller":"traceutil/trace.go:171","msg":"trace[757852391] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:751; }","duration":"114.650655ms","start":"2026-02-06T20:04:22.607537Z","end":"2026-02-06T20:04:22.722188Z","steps":["trace[757852391] 'agreement among raft nodes before linearized reading' (duration: 114.487371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:22.722523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.928152ms","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-06T20:04:22.722549Z","caller":"traceutil/trace.go:171","msg":"trace[1696587800] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:751; }","duration":"114.966173ms","start":"2026-02-06T20:04:22.607577Z","end":"2026-02-06T20:04:22.722543Z","steps":["trace[1696587800] 'agreement among raft nodes before linearized reading' (duration: 114.801129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:22.722807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.094706ms","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-06T20:04:22.72284Z","caller":"traceutil/trace.go:171","msg":"trace[1965102372] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:751; }","duration":"130.130457ms","start":"2026-02-06T20:04:22.592703Z","end":"2026-02-06T20:04:22.722833Z","steps":["trace[1965102372] 'agreement among raft nodes before linearized reading' (duration: 129.927062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:25.824451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.997086ms","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-06T20:04:25.824573Z","caller":"traceutil/trace.go:171","msg":"trace[181865811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:764; }","duration":"123.139599ms","start":"2026-02-06T20:04:25.701393Z","end":"2026-02-06T20:04:25.824533Z","steps":["trace[181865811] 'range keys from in-memory index tree' (duration: 122.829893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:25.825175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.038974ms","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-06T20:04:25.8253Z","caller":"traceutil/trace.go:171","msg":"trace[370665405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:764; }","duration":"126.223678ms","start":"2026-02-06T20:04:25.699053Z","end":"2026-02-06T20:04:25.825277Z","steps":["trace[370665405] 'range keys from in-memory index tree' (duration: 125.846039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:25.825596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.639594ms","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-06T20:04:25.825627Z","caller":"traceutil/trace.go:171","msg":"trace[1790373293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:764; }","duration":"125.679865ms","start":"2026-02-06T20:04:25.699937Z","end":"2026-02-06T20:04:25.825617Z","steps":["trace[1790373293] 'range keys from in-memory index tree' (duration: 125.555531ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:04:30.730078Z","caller":"traceutil/trace.go:171","msg":"trace[847351329] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"171.596202ms","start":"2026-02-06T20:04:30.558458Z","end":"2026-02-06T20:04:30.730054Z","steps":["trace[847351329] 'process raft request' (duration: 171.462599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:32.055863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252641691s","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-06T20:04:32.055942Z","caller":"traceutil/trace.go:171","msg":"trace[264814865] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:778; }","duration":"1.252796864s","start":"2026-02-06T20:04:30.803127Z","end":"2026-02-06T20:04:32.055924Z","steps":["trace[264814865] 'range keys from in-memory index tree' (duration: 1.252449906s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:32.05598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:30.803102Z","time spent":"1.252871917s","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-06T20:04:32.147115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.623283ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-06T20:04:32.147209Z","caller":"traceutil/trace.go:171","msg":"trace[70472015] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:807; }","duration":"410.049324ms","start":"2026-02-06T20:04:31.737144Z","end":"2026-02-06T20:04:32.147193Z","steps":["trace[70472015] 'read index received' (duration: 41.861µs)","trace[70472015] 'applied index is now lower than readState.Index' (duration: 410.006113ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:04:32.147397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.248319ms","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-06T20:04:32.147412Z","caller":"traceutil/trace.go:171","msg":"trace[934167649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:778; }","duration":"410.32118ms","start":"2026-02-06T20:04:31.737085Z","end":"2026-02-06T20:04:32.147406Z","steps":["trace[934167649] 'agreement among raft nodes before linearized reading' (duration: 410.194557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:32.147431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:31.737063Z","time spent":"410.363352ms","remote":"127.0.0.1:57302","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-06T20:04:32.227452Z","caller":"traceutil/trace.go:171","msg":"trace[248513471] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"166.952334ms","start":"2026-02-06T20:04:32.06047Z","end":"2026-02-06T20:04:32.227423Z","steps":["trace[248513471] 'process raft request' (duration: 166.767269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:32.228337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.647192ms","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-06T20:04:32.22838Z","caller":"traceutil/trace.go:171","msg":"trace[1452053549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:779; }","duration":"309.757685ms","start":"2026-02-06T20:04:31.91861Z","end":"2026-02-06T20:04:32.228368Z","steps":["trace[1452053549] 'agreement among raft nodes before linearized reading' (duration: 309.532478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:32.22841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:31.918589Z","time spent":"309.813115ms","remote":"127.0.0.1:57302","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-06T20:04:32.228624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.186274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:04:32.228643Z","caller":"traceutil/trace.go:171","msg":"trace[1726270015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"223.205124ms","start":"2026-02-06T20:04:32.005432Z","end":"2026-02-06T20:04:32.228637Z","steps":["trace[1726270015] 'agreement among raft nodes before linearized reading' (duration: 223.169903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:32.228782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.410578ms","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-06T20:04:32.228803Z","caller":"traceutil/trace.go:171","msg":"trace[576681286] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:779; }","duration":"280.437898ms","start":"2026-02-06T20:04:31.948357Z","end":"2026-02-06T20:04:32.228794Z","steps":["trace[576681286] 'agreement among raft nodes before linearized reading' (duration: 280.383597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:32.228906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.255845ms","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-06T20:04:32.228925Z","caller":"traceutil/trace.go:171","msg":"trace[1544571388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:779; }","duration":"293.282605ms","start":"2026-02-06T20:04:31.935635Z","end":"2026-02-06T20:04:32.228917Z","steps":["trace[1544571388] 'agreement among raft nodes before linearized reading' (duration: 293.231514ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:04:40.577303Z","caller":"traceutil/trace.go:171","msg":"trace[809755137] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"303.119535ms","start":"2026-02-06T20:04:40.27414Z","end":"2026-02-06T20:04:40.57726Z","steps":["trace[809755137] 'process raft request' (duration: 302.968121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:40.577609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:40.274128Z","time spent":"303.253139ms","remote":"127.0.0.1:57302","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-06T20:04:40.578014Z","caller":"traceutil/trace.go:171","msg":"trace[2107752313] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:832; }","duration":"281.322251ms","start":"2026-02-06T20:04:40.296683Z","end":"2026-02-06T20:04:40.578006Z","steps":["trace[2107752313] 'read index received' (duration: 281.318911ms)","trace[2107752313] 'applied index is now lower than readState.Index' (duration: 2.6µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:04:40.57814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.465195ms","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-06T20:04:40.578163Z","caller":"traceutil/trace.go:171","msg":"trace[482871390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"281.549157ms","start":"2026-02-06T20:04:40.296607Z","end":"2026-02-06T20:04:40.578156Z","steps":["trace[482871390] 'agreement among raft nodes before linearized reading' (duration: 281.438474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:41.179509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.09261ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-06T20:04:41.179734Z","caller":"traceutil/trace.go:171","msg":"trace[361327304] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:832; }","duration":"601.686735ms","start":"2026-02-06T20:04:40.578032Z","end":"2026-02-06T20:04:41.179718Z","steps":["trace[361327304] 'read index received' (duration: 321.309728ms)","trace[361327304] 'applied index is now lower than readState.Index' (duration: 280.376057ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:04:41.179916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.493509ms","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-06T20:04:41.179959Z","caller":"traceutil/trace.go:171","msg":"trace[1164069399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:801; }","duration":"874.56495ms","start":"2026-02-06T20:04:40.305387Z","end":"2026-02-06T20:04:41.179952Z","steps":["trace[1164069399] 'agreement among raft nodes before linearized reading' (duration: 874.407906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:41.179983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:40.305382Z","time spent":"874.595401ms","remote":"127.0.0.1:57302","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-06T20:04:41.180196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.822187ms","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-06T20:04:41.180215Z","caller":"traceutil/trace.go:171","msg":"trace[135854563] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:801; }","duration":"874.864138ms","start":"2026-02-06T20:04:40.305345Z","end":"2026-02-06T20:04:41.180209Z","steps":["trace[135854563] 'agreement among raft nodes before linearized reading' (duration: 874.808896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:41.180232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:40.305334Z","time spent":"874.893828ms","remote":"127.0.0.1:57302","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-06T20:04:41.18034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.734161ms","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-06T20:04:41.180356Z","caller":"traceutil/trace.go:171","msg":"trace[1800466134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:801; }","duration":"877.806402ms","start":"2026-02-06T20:04:40.302544Z","end":"2026-02-06T20:04:41.180351Z","steps":["trace[1800466134] 'agreement among raft nodes before linearized reading' (duration: 877.760131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:41.180372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:40.302528Z","time spent":"877.840594ms","remote":"127.0.0.1:57302","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-06T20:04:41.182105Z","caller":"traceutil/trace.go:171","msg":"trace[34290855] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"601.042338ms","start":"2026-02-06T20:04:40.581053Z","end":"2026-02-06T20:04:41.182095Z","steps":["trace[34290855] 'process raft request' (duration: 598.571535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:41.182179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:40.581037Z","time spent":"601.092529ms","remote":"127.0.0.1:57302","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-06T20:04:41.279444Z","caller":"traceutil/trace.go:171","msg":"trace[1683806233] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"224.427064ms","start":"2026-02-06T20:04:41.054956Z","end":"2026-02-06T20:04:41.279383Z","steps":["trace[1683806233] 'process raft request' (duration: 195.435008ms)","trace[1683806233] 'compare' (duration: 28.412202ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:04:41.279205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.445436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:04:41.28594Z","caller":"traceutil/trace.go:171","msg":"trace[1086785272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"261.23649ms","start":"2026-02-06T20:04:41.024695Z","end":"2026-02-06T20:04:41.285931Z","steps":["trace[1086785272] 'agreement among raft nodes before linearized reading' (duration: 254.436816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:50.764125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.30021ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T20:04:50.764246Z","caller":"traceutil/trace.go:171","msg":"trace[399283799] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"123.315015ms","start":"2026-02-06T20:04:50.640913Z","end":"2026-02-06T20:04:50.764228Z","steps":["trace[399283799] 'compare' (duration: 113.158506ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:04:50.766098Z","caller":"traceutil/trace.go:171","msg":"trace[524784923] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"115.137397ms","start":"2026-02-06T20:04:50.650951Z","end":"2026-02-06T20:04:50.766088Z","steps":["trace[524784923] 'process raft request' (duration: 115.008653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:54.149583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.6336ms","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-06T20:04:54.14967Z","caller":"traceutil/trace.go:171","msg":"trace[504407229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:887; }","duration":"702.800234ms","start":"2026-02-06T20:04:53.44685Z","end":"2026-02-06T20:04:54.14965Z","steps":["trace[504407229] 'range keys from in-memory index tree' (duration: 702.447566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:54.14971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:53.44682Z","time spent":"702.882167ms","remote":"127.0.0.1:57302","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-06T20:04:54.149923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.659455ms","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":"warn","ts":"2026-02-06T20:04:54.150025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.508149ms","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-06T20:04:54.150051Z","caller":"traceutil/trace.go:171","msg":"trace[1150015290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:887; }","duration":"704.56849ms","start":"2026-02-06T20:04:53.445472Z","end":"2026-02-06T20:04:54.150041Z","steps":["trace[1150015290] 'range keys from in-memory index tree' (duration: 704.425207ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:04:54.150053Z","caller":"traceutil/trace.go:171","msg":"trace[1758035478] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:887; }","duration":"712.802879ms","start":"2026-02-06T20:04:53.437233Z","end":"2026-02-06T20:04:54.150035Z","steps":["trace[1758035478] 'range keys from in-memory index tree' (duration: 712.454911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:54.15007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:53.445452Z","time spent":"704.613881ms","remote":"127.0.0.1:57302","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-06T20:04:54.150088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:53.437168Z","time spent":"712.912242ms","remote":"127.0.0.1:57302","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":"info","ts":"2026-02-06T20:04:54.214815Z","caller":"traceutil/trace.go:171","msg":"trace[1338579862] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"156.947319ms","start":"2026-02-06T20:04:54.057842Z","end":"2026-02-06T20:04:54.214789Z","steps":["trace[1338579862] 'process raft request' (duration: 156.802295ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:04:55.204688Z","caller":"traceutil/trace.go:171","msg":"trace[570465099] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"413.04924ms","start":"2026-02-06T20:04:54.791613Z","end":"2026-02-06T20:04:55.204662Z","steps":["trace[570465099] 'process raft request' (duration: 412.905316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:55.205154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:54.791597Z","time spent":"413.170812ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:04:55.206056Z","caller":"traceutil/trace.go:171","msg":"trace[1980217040] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:927; }","duration":"197.955353ms","start":"2026-02-06T20:04:55.008062Z","end":"2026-02-06T20:04:55.206018Z","steps":["trace[1980217040] 'read index received' (duration: 197.944402ms)","trace[1980217040] 'applied index is now lower than readState.Index' (duration: 8.401µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:04:55.206405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.309751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:04:55.2066Z","caller":"traceutil/trace.go:171","msg":"trace[183145924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:892; }","duration":"198.587728ms","start":"2026-02-06T20:04:55.007997Z","end":"2026-02-06T20:04:55.206585Z","steps":["trace[183145924] 'agreement among raft nodes before linearized reading' (duration: 198.300091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:56.160141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.977549ms","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-06T20:04:56.160241Z","caller":"traceutil/trace.go:171","msg":"trace[1930047375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:892; }","duration":"847.145753ms","start":"2026-02-06T20:04:55.313071Z","end":"2026-02-06T20:04:56.160217Z","steps":["trace[1930047375] 'range keys from in-memory index tree' (duration: 846.784784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:56.160283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:55.313046Z","time spent":"847.228126ms","remote":"127.0.0.1:57302","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-06T20:04:56.160313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.78588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.217\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-06T20:04:56.160385Z","caller":"traceutil/trace.go:171","msg":"trace[1213520345] range","detail":"{range_begin:/registry/masterleases/162.253.55.217; range_end:; response_count:1; response_revision:892; }","duration":"714.950295ms","start":"2026-02-06T20:04:55.445415Z","end":"2026-02-06T20:04:56.160366Z","steps":["trace[1213520345] 'range keys from in-memory index tree' (duration: 714.600285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:56.160414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:55.445389Z","time spent":"715.019536ms","remote":"127.0.0.1:57058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.217\" "} {"level":"warn","ts":"2026-02-06T20:04:57.317466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.191685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T20:04:57.317582Z","caller":"traceutil/trace.go:171","msg":"trace[1586751440] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:928; }","duration":"1.07437176s","start":"2026-02-06T20:04:56.243191Z","end":"2026-02-06T20:04:57.317563Z","steps":["trace[1586751440] 'read index received' (duration: 191.794535ms)","trace[1586751440] 'applied index is now lower than readState.Index' (duration: 882.575445ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:04:57.317608Z","caller":"traceutil/trace.go:171","msg":"trace[580871675] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"1.152499106s","start":"2026-02-06T20:04:56.165074Z","end":"2026-02-06T20:04:57.317573Z","steps":["trace[580871675] 'process raft request' (duration: 1.021087516s)","trace[580871675] 'compare' (duration: 130.873166ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:04:57.317759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074566145s","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":"warn","ts":"2026-02-06T20:04:57.31777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:56.165059Z","time spent":"1.15262727s","remote":"127.0.0.1:57302","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-06T20:04:57.31778Z","caller":"traceutil/trace.go:171","msg":"trace[1275876608] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:893; }","duration":"1.074638547s","start":"2026-02-06T20:04:56.243134Z","end":"2026-02-06T20:04:57.317772Z","steps":["trace[1275876608] 'agreement among raft nodes before linearized reading' (duration: 1.074497374s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:57.317809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:56.243114Z","time spent":"1.074689668s","remote":"127.0.0.1:57302","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":"info","ts":"2026-02-06T20:04:57.473622Z","caller":"traceutil/trace.go:171","msg":"trace[1260096801] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"1.038021495s","start":"2026-02-06T20:04:56.435563Z","end":"2026-02-06T20:04:57.473584Z","steps":["trace[1260096801] 'process raft request' (duration: 1.01380417s)","trace[1260096801] 'compare' (duration: 24.060911ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:04:57.473665Z","caller":"traceutil/trace.go:171","msg":"trace[2054085849] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"150.330381ms","start":"2026-02-06T20:04:57.32331Z","end":"2026-02-06T20:04:57.473641Z","steps":["trace[2054085849] 'process raft request' (duration: 150.282609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:57.473792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:56.435548Z","time spent":"1.038156139s","remote":"127.0.0.1:57058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:04:57.474227Z","caller":"traceutil/trace.go:171","msg":"trace[1259220836] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:929; }","duration":"156.614771ms","start":"2026-02-06T20:04:57.317605Z","end":"2026-02-06T20:04:57.47422Z","steps":["trace[1259220836] 'read index received' (duration: 131.772339ms)","trace[1259220836] 'applied index is now lower than readState.Index' (duration: 24.841832ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:04:57.474324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229807911s","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-06T20:04:57.474341Z","caller":"traceutil/trace.go:171","msg":"trace[12746458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:895; }","duration":"1.229848022s","start":"2026-02-06T20:04:56.244488Z","end":"2026-02-06T20:04:57.474336Z","steps":["trace[12746458] 'agreement among raft nodes before linearized reading' (duration: 1.22975287s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:57.474356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:56.244479Z","time spent":"1.229872022s","remote":"127.0.0.1:57302","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-06T20:04:57.474426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.327399ms","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-06T20:04:57.474436Z","caller":"traceutil/trace.go:171","msg":"trace[487714163] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:895; }","duration":"264.379471ms","start":"2026-02-06T20:04:57.210053Z","end":"2026-02-06T20:04:57.474433Z","steps":["trace[487714163] 'agreement among raft nodes before linearized reading' (duration: 264.346011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:57.47448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.249974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:04:57.474495Z","caller":"traceutil/trace.go:171","msg":"trace[959126964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"468.308245ms","start":"2026-02-06T20:04:57.006182Z","end":"2026-02-06T20:04:57.47449Z","steps":["trace[959126964] 'agreement among raft nodes before linearized reading' (duration: 468.287054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:57.474505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:57.00616Z","time spent":"468.342556ms","remote":"127.0.0.1:57050","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-06T20:04:57.474576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.68002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:04:57.474587Z","caller":"traceutil/trace.go:171","msg":"trace[855773123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"949.735531ms","start":"2026-02-06T20:04:56.524848Z","end":"2026-02-06T20:04:57.474584Z","steps":["trace[855773123] 'agreement among raft nodes before linearized reading' (duration: 949.715691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:57.474599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:56.524827Z","time spent":"949.769483ms","remote":"127.0.0.1:57044","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-06T20:04:57.474695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.230150309s","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-06T20:04:57.474706Z","caller":"traceutil/trace.go:171","msg":"trace[1002896299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:895; }","duration":"1.23017548s","start":"2026-02-06T20:04:56.244527Z","end":"2026-02-06T20:04:57.474703Z","steps":["trace[1002896299] 'agreement among raft nodes before linearized reading' (duration: 1.230135179s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:04:57.474718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:04:56.244522Z","time spent":"1.230192531s","remote":"127.0.0.1:57302","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-06T20:05:00.873374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.410232ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-06T20:05:01.73139Z","caller":"traceutil/trace.go:171","msg":"trace[1056093363] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"398.446678ms","start":"2026-02-06T20:05:01.332934Z","end":"2026-02-06T20:05:01.731381Z","steps":["trace[1056093363] 'process raft request' (duration: 398.14577ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:05:01.731518Z","caller":"traceutil/trace.go:171","msg":"trace[408812350] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"231.507825ms","start":"2026-02-06T20:05:01.499735Z","end":"2026-02-06T20:05:01.731243Z","steps":["trace[408812350] 'read index received' (duration: 231.464954ms)","trace[408812350] 'applied index is now lower than readState.Index' (duration: 42.181µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:05:01.731603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:01.332909Z","time spent":"398.546951ms","remote":"127.0.0.1:57302","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-06T20:05:01.732072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.331416ms","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-06T20:05:01.732095Z","caller":"traceutil/trace.go:171","msg":"trace[329069475] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:904; }","duration":"232.357917ms","start":"2026-02-06T20:05:01.499731Z","end":"2026-02-06T20:05:01.732089Z","steps":["trace[329069475] 'agreement among raft nodes before linearized reading' (duration: 231.818413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:02.411491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.523572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T20:05:02.411673Z","caller":"traceutil/trace.go:171","msg":"trace[1366518745] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"676.330241ms","start":"2026-02-06T20:05:01.735324Z","end":"2026-02-06T20:05:02.411654Z","steps":["trace[1366518745] 'process raft request' (duration: 676.27867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:02.411738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:01.735311Z","time spent":"676.395173ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:05:02.412054Z","caller":"traceutil/trace.go:171","msg":"trace[1758069702] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"844.541337ms","start":"2026-02-06T20:05:01.567501Z","end":"2026-02-06T20:05:02.412042Z","steps":["trace[1758069702] 'process raft request' (duration: 323.377219ms)","trace[1758069702] 'compare' (duration: 520.193694ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:05:02.41211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:01.567485Z","time spent":"844.595239ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:05:02.412229Z","caller":"traceutil/trace.go:171","msg":"trace[1005643954] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:941; }","duration":"680.685552ms","start":"2026-02-06T20:05:01.731531Z","end":"2026-02-06T20:05:02.412216Z","steps":["trace[1005643954] 'read index received' (duration: 159.363341ms)","trace[1005643954] 'applied index is now lower than readState.Index' (duration: 521.321211ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:05:02.412351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.799936ms","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-06T20:05:02.412375Z","caller":"traceutil/trace.go:171","msg":"trace[963617898] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:906; }","duration":"907.834886ms","start":"2026-02-06T20:05:01.504532Z","end":"2026-02-06T20:05:02.412367Z","steps":["trace[963617898] 'agreement among raft nodes before linearized reading' (duration: 907.718683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:02.412402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:01.504527Z","time spent":"907.868647ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-06T20:05:02.412652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.187105ms","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-06T20:05:02.412684Z","caller":"traceutil/trace.go:171","msg":"trace[2038233975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:906; }","duration":"777.264277ms","start":"2026-02-06T20:05:01.635412Z","end":"2026-02-06T20:05:02.412676Z","steps":["trace[2038233975] 'agreement among raft nodes before linearized reading' (duration: 777.181555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:02.412709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:01.635397Z","time spent":"777.306898ms","remote":"127.0.0.1:57302","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-06T20:05:02.412861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.743175ms","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-06T20:05:02.412885Z","caller":"traceutil/trace.go:171","msg":"trace[49106883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:906; }","duration":"900.767076ms","start":"2026-02-06T20:05:01.512111Z","end":"2026-02-06T20:05:02.412878Z","steps":["trace[49106883] 'agreement among raft nodes before linearized reading' (duration: 900.691294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:02.412909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:01.512096Z","time spent":"900.806838ms","remote":"127.0.0.1:57302","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-06T20:05:02.666777Z","caller":"traceutil/trace.go:171","msg":"trace[1783369299] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"248.017423ms","start":"2026-02-06T20:05:02.418736Z","end":"2026-02-06T20:05:02.666754Z","steps":["trace[1783369299] 'process raft request' (duration: 170.535384ms)","trace[1783369299] 'compare' (duration: 76.019433ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:05:02.667099Z","caller":"traceutil/trace.go:171","msg":"trace[902529294] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"246.515246ms","start":"2026-02-06T20:05:02.420575Z","end":"2026-02-06T20:05:02.66709Z","steps":["trace[902529294] 'process raft request' (duration: 244.990208ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:05:02.667244Z","caller":"traceutil/trace.go:171","msg":"trace[1497845148] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"245.649953ms","start":"2026-02-06T20:05:02.421547Z","end":"2026-02-06T20:05:02.667197Z","steps":["trace[1497845148] 'process raft request' (duration: 244.065744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:07.152181Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021192883480278,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T20:05:07.441717Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.010016824s","expected-duration":"1s"} {"level":"info","ts":"2026-02-06T20:05:07.442047Z","caller":"traceutil/trace.go:171","msg":"trace[314512637] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"790.560626ms","start":"2026-02-06T20:05:06.651466Z","end":"2026-02-06T20:05:07.442027Z","steps":["trace[314512637] 'read index received' (duration: 790.377792ms)","trace[314512637] 'applied index is now lower than readState.Index' (duration: 182.154µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:05:07.442239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.74159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T20:05:07.442303Z","caller":"traceutil/trace.go:171","msg":"trace[2037987929] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:918; }","duration":"790.872384ms","start":"2026-02-06T20:05:06.651414Z","end":"2026-02-06T20:05:07.442287Z","steps":["trace[2037987929] 'agreement among raft nodes before linearized reading' (duration: 790.71523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:07.442343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:06.651394Z","time spent":"790.942666ms","remote":"127.0.0.1:57500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-06T20:05:07.442436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.796025ms","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-06T20:05:07.442463Z","caller":"traceutil/trace.go:171","msg":"trace[1328806519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:918; }","duration":"761.881427ms","start":"2026-02-06T20:05:06.680572Z","end":"2026-02-06T20:05:07.442454Z","steps":["trace[1328806519] 'agreement among raft nodes before linearized reading' (duration: 761.756134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:07.442485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:06.680549Z","time spent":"761.930637ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-06T20:05:07.442529Z","caller":"traceutil/trace.go:171","msg":"trace[1525954850] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"1.010888776s","start":"2026-02-06T20:05:06.431632Z","end":"2026-02-06T20:05:07.44252Z","steps":["trace[1525954850] 'process raft request' (duration: 1.010268321s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:07.442589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:06.43162Z","time spent":"1.010923157s","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:05:07.442626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.141766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:05:07.442647Z","caller":"traceutil/trace.go:171","msg":"trace[1118514790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:918; }","duration":"434.195877ms","start":"2026-02-06T20:05:07.008446Z","end":"2026-02-06T20:05:07.442641Z","steps":["trace[1118514790] 'agreement among raft nodes before linearized reading' (duration: 434.159607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:07.44266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:07.008428Z","time spent":"434.228818ms","remote":"127.0.0.1:57044","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-06T20:05:07.442771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.815263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:05:07.442786Z","caller":"traceutil/trace.go:171","msg":"trace[325788260] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:918; }","duration":"600.869164ms","start":"2026-02-06T20:05:06.841911Z","end":"2026-02-06T20:05:07.442781Z","steps":["trace[325788260] 'agreement among raft nodes before linearized reading' (duration: 600.836353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:07.442799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:06.841893Z","time spent":"600.902975ms","remote":"127.0.0.1:57446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-02-06T20:05:07.442901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.129464ms","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-06T20:05:07.44292Z","caller":"traceutil/trace.go:171","msg":"trace[1639995563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:918; }","duration":"753.179865ms","start":"2026-02-06T20:05:06.689734Z","end":"2026-02-06T20:05:07.442914Z","steps":["trace[1639995563] 'agreement among raft nodes before linearized reading' (duration: 753.075183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:07.442933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:06.689711Z","time spent":"753.218477ms","remote":"127.0.0.1:57302","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-06T20:05:07.443054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.443517ms","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-06T20:05:07.443067Z","caller":"traceutil/trace.go:171","msg":"trace[111105514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:918; }","duration":"752.486238ms","start":"2026-02-06T20:05:06.690576Z","end":"2026-02-06T20:05:07.443063Z","steps":["trace[111105514] 'agreement among raft nodes before linearized reading' (duration: 752.444717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:07.443088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:06.690559Z","time spent":"752.52345ms","remote":"127.0.0.1:57302","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-06T20:05:08.004954Z","caller":"traceutil/trace.go:171","msg":"trace[389628183] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"557.43419ms","start":"2026-02-06T20:05:07.447497Z","end":"2026-02-06T20:05:08.004931Z","steps":["trace[389628183] 'process raft request' (duration: 525.126488ms)","trace[389628183] 'compare' (duration: 32.129027ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:05:08.005092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:07.447488Z","time spent":"557.518352ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:05:08.030784Z","caller":"traceutil/trace.go:171","msg":"trace[500995811] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:956; }","duration":"278.742276ms","start":"2026-02-06T20:05:07.752014Z","end":"2026-02-06T20:05:08.030757Z","steps":["trace[500995811] 'read index received' (duration: 220.633969ms)","trace[500995811] 'applied index is now lower than readState.Index' (duration: 58.107357ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:05:08.031292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.048803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T20:05:08.031323Z","caller":"traceutil/trace.go:171","msg":"trace[1939781512] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:921; }","duration":"135.197986ms","start":"2026-02-06T20:05:07.896118Z","end":"2026-02-06T20:05:08.031316Z","steps":["trace[1939781512] 'agreement among raft nodes before linearized reading' (duration: 135.025102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:08.031262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.194577ms","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-06T20:05:08.031362Z","caller":"traceutil/trace.go:171","msg":"trace[243633397] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:921; }","duration":"279.364322ms","start":"2026-02-06T20:05:07.751979Z","end":"2026-02-06T20:05:08.031343Z","steps":["trace[243633397] 'agreement among raft nodes before linearized reading' (duration: 279.044474ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:05:08.031573Z","caller":"traceutil/trace.go:171","msg":"trace[1830451304] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"552.298169ms","start":"2026-02-06T20:05:07.479268Z","end":"2026-02-06T20:05:08.031566Z","steps":["trace[1830451304] 'process raft request' (duration: 551.343735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:08.031638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:07.479253Z","time spent":"552.33942ms","remote":"127.0.0.1:57302","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-06T20:05:08.031656Z","caller":"traceutil/trace.go:171","msg":"trace[307556040] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"552.32464ms","start":"2026-02-06T20:05:07.479313Z","end":"2026-02-06T20:05:08.031638Z","steps":["trace[307556040] 'process raft request' (duration: 551.418647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:08.031756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:07.479307Z","time spent":"552.367461ms","remote":"127.0.0.1:57302","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-06T20:05:09.931503Z","caller":"traceutil/trace.go:171","msg":"trace[120867026] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"474.448005ms","start":"2026-02-06T20:05:09.457034Z","end":"2026-02-06T20:05:09.931482Z","steps":["trace[120867026] 'process raft request' (duration: 474.320772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:09.931637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:09.457023Z","time spent":"474.543207ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:05:10.200772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.141019ms","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-06T20:05:10.200838Z","caller":"traceutil/trace.go:171","msg":"trace[1359164077] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:923; }","duration":"192.257082ms","start":"2026-02-06T20:05:10.008566Z","end":"2026-02-06T20:05:10.200824Z","steps":["trace[1359164077] 'range keys from in-memory index tree' (duration: 191.990806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:10.201002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.905131ms","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-06T20:05:10.201017Z","caller":"traceutil/trace.go:171","msg":"trace[1429439815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:923; }","duration":"158.952202ms","start":"2026-02-06T20:05:10.04206Z","end":"2026-02-06T20:05:10.201012Z","steps":["trace[1429439815] 'range keys from in-memory index tree' (duration: 158.803799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:10.201111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.200272ms","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-06T20:05:10.201128Z","caller":"traceutil/trace.go:171","msg":"trace[607814245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:923; }","duration":"154.262563ms","start":"2026-02-06T20:05:10.046861Z","end":"2026-02-06T20:05:10.201124Z","steps":["trace[607814245] 'range keys from in-memory index tree' (duration: 154.115529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:10.201226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.432281ms","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-06T20:05:10.201256Z","caller":"traceutil/trace.go:171","msg":"trace[1474439003] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:923; }","duration":"103.500332ms","start":"2026-02-06T20:05:10.097751Z","end":"2026-02-06T20:05:10.201252Z","steps":["trace[1474439003] 'range keys from in-memory index tree' (duration: 103.283217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:12.491166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.086885ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-06T20:05:12.491369Z","caller":"traceutil/trace.go:171","msg":"trace[28120779] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"554.049975ms","start":"2026-02-06T20:05:11.937296Z","end":"2026-02-06T20:05:12.491346Z","steps":["trace[28120779] 'read index received' (duration: 62.663222ms)","trace[28120779] 'applied index is now lower than readState.Index' (duration: 491.383833ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:05:12.491626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.440177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:05:12.491699Z","caller":"traceutil/trace.go:171","msg":"trace[423130992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:927; }","duration":"486.56748ms","start":"2026-02-06T20:05:12.005109Z","end":"2026-02-06T20:05:12.491677Z","steps":["trace[423130992] 'agreement among raft nodes before linearized reading' (duration: 486.432467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:12.491733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:12.005087Z","time spent":"486.637512ms","remote":"127.0.0.1:57044","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-06T20:05:12.491747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.425804ms","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-06T20:05:12.491809Z","caller":"traceutil/trace.go:171","msg":"trace[1218707111] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:927; }","duration":"554.531917ms","start":"2026-02-06T20:05:11.937262Z","end":"2026-02-06T20:05:12.491794Z","steps":["trace[1218707111] 'agreement among raft nodes before linearized reading' (duration: 554.25392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:12.492093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.463752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-06T20:05:12.492113Z","caller":"traceutil/trace.go:171","msg":"trace[2022847536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:927; }","duration":"256.508574ms","start":"2026-02-06T20:05:12.235598Z","end":"2026-02-06T20:05:12.492106Z","steps":["trace[2022847536] 'agreement among raft nodes before linearized reading' (duration: 256.456132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:12.492228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.331434ms","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-06T20:05:12.492244Z","caller":"traceutil/trace.go:171","msg":"trace[543347278] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:927; }","duration":"257.356705ms","start":"2026-02-06T20:05:12.234881Z","end":"2026-02-06T20:05:12.492238Z","steps":["trace[543347278] 'agreement among raft nodes before linearized reading' (duration: 257.309234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:12.492344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.886029ms","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-06T20:05:12.492359Z","caller":"traceutil/trace.go:171","msg":"trace[1062855797] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:927; }","duration":"259.93008ms","start":"2026-02-06T20:05:12.232423Z","end":"2026-02-06T20:05:12.492353Z","steps":["trace[1062855797] 'agreement among raft nodes before linearized reading' (duration: 259.884189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:12.491849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:11.937245Z","time spent":"554.595808ms","remote":"127.0.0.1:57302","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":"info","ts":"2026-02-06T20:05:12.616181Z","caller":"traceutil/trace.go:171","msg":"trace[507818481] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"113.021963ms","start":"2026-02-06T20:05:12.50314Z","end":"2026-02-06T20:05:12.616162Z","steps":["trace[507818481] 'process raft request' (duration: 112.854969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:13.070325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.50055ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T20:05:13.070741Z","caller":"traceutil/trace.go:171","msg":"trace[1780477732] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"567.364901ms","start":"2026-02-06T20:05:12.503347Z","end":"2026-02-06T20:05:13.070711Z","steps":["trace[1780477732] 'process raft request' (duration: 567.094434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:13.070926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:12.503339Z","time spent":"567.468653ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:05:13.070957Z","caller":"traceutil/trace.go:171","msg":"trace[1814479077] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"563.845252ms","start":"2026-02-06T20:05:12.507087Z","end":"2026-02-06T20:05:13.070933Z","steps":["trace[1814479077] 'process raft request' (duration: 563.408481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:13.071037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:12.507048Z","time spent":"563.948904ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:05:13.071249Z","caller":"traceutil/trace.go:171","msg":"trace[1026169929] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"559.42183ms","start":"2026-02-06T20:05:12.51178Z","end":"2026-02-06T20:05:13.071202Z","steps":["trace[1026169929] 'process raft request' (duration: 558.755784ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:05:13.071272Z","caller":"traceutil/trace.go:171","msg":"trace[455301210] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"567.968486ms","start":"2026-02-06T20:05:12.503293Z","end":"2026-02-06T20:05:13.071262Z","steps":["trace[455301210] 'process raft request' (duration: 288.402209ms)","trace[455301210] 'compare' (duration: 278.349026ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:05:13.071309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:12.511773Z","time spent":"559.503932ms","remote":"127.0.0.1:57302","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-06T20:05:13.071312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:12.503283Z","time spent":"568.007526ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:05:13.071392Z","caller":"traceutil/trace.go:171","msg":"trace[537542121] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"487.995727ms","start":"2026-02-06T20:05:12.583388Z","end":"2026-02-06T20:05:13.071383Z","steps":["trace[537542121] 'process raft request' (duration: 487.184907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:13.071463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:12.583347Z","time spent":"488.066928ms","remote":"127.0.0.1:57302","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-06T20:05:13.322339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.153304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T20:05:13.322408Z","caller":"traceutil/trace.go:171","msg":"trace[1734777021] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:933; }","duration":"145.291827ms","start":"2026-02-06T20:05:13.1771Z","end":"2026-02-06T20:05:13.322392Z","steps":["trace[1734777021] 'count revisions from in-memory index tree' (duration: 145.077851ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:05:14.993561Z","caller":"traceutil/trace.go:171","msg":"trace[2127805831] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"367.516146ms","start":"2026-02-06T20:05:14.626022Z","end":"2026-02-06T20:05:14.993538Z","steps":["trace[2127805831] 'process raft request' (duration: 367.339291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:14.9937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:14.626004Z","time spent":"367.619999ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T20:05:15.370646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.064172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T20:05:15.370757Z","caller":"traceutil/trace.go:171","msg":"trace[1452235540] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:972; }","duration":"484.113729ms","start":"2026-02-06T20:05:14.886621Z","end":"2026-02-06T20:05:15.370734Z","steps":["trace[1452235540] 'read index received' (duration: 106.844837ms)","trace[1452235540] 'applied index is now lower than readState.Index' (duration: 377.267032ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:05:15.37103Z","caller":"traceutil/trace.go:171","msg":"trace[346369276] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"598.722902ms","start":"2026-02-06T20:05:14.772276Z","end":"2026-02-06T20:05:15.370999Z","steps":["trace[346369276] 'process raft request' (duration: 495.212789ms)","trace[346369276] 'compare' (duration: 102.737814ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:05:15.371142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:14.772243Z","time spent":"598.818274ms","remote":"127.0.0.1:57302","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-06T20:05:15.371424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.792846ms","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-06T20:05:15.371462Z","caller":"traceutil/trace.go:171","msg":"trace[1308671018] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:935; }","duration":"484.874928ms","start":"2026-02-06T20:05:14.886575Z","end":"2026-02-06T20:05:15.37145Z","steps":["trace[1308671018] 'agreement among raft nodes before linearized reading' (duration: 484.806256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:15.371493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:14.886562Z","time spent":"484.923089ms","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-06T20:05:15.371651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.93671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:05:15.371677Z","caller":"traceutil/trace.go:171","msg":"trace[1778411488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"360.990001ms","start":"2026-02-06T20:05:15.010678Z","end":"2026-02-06T20:05:15.371668Z","steps":["trace[1778411488] 'agreement among raft nodes before linearized reading' (duration: 360.95044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:15.371773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:15.010662Z","time spent":"361.104244ms","remote":"127.0.0.1:57044","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-06T20:05:15.372094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.329387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-06T20:05:15.372133Z","caller":"traceutil/trace.go:171","msg":"trace[1360618162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:935; }","duration":"290.377419ms","start":"2026-02-06T20:05:15.081744Z","end":"2026-02-06T20:05:15.372121Z","steps":["trace[1360618162] 'agreement among raft nodes before linearized reading' (duration: 290.223765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:15.372324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.56653ms","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-06T20:05:15.37235Z","caller":"traceutil/trace.go:171","msg":"trace[1820271407] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:935; }","duration":"291.647832ms","start":"2026-02-06T20:05:15.080694Z","end":"2026-02-06T20:05:15.372342Z","steps":["trace[1820271407] 'agreement among raft nodes before linearized reading' (duration: 291.56499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:15.372506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.284307ms","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-06T20:05:15.372531Z","caller":"traceutil/trace.go:171","msg":"trace[1797508987] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:935; }","duration":"292.343039ms","start":"2026-02-06T20:05:15.08018Z","end":"2026-02-06T20:05:15.372523Z","steps":["trace[1797508987] 'agreement among raft nodes before linearized reading' (duration: 292.270927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:15.372687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.485238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-06T20:05:15.372712Z","caller":"traceutil/trace.go:171","msg":"trace[58993720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:935; }","duration":"293.538038ms","start":"2026-02-06T20:05:15.079166Z","end":"2026-02-06T20:05:15.372704Z","steps":["trace[58993720] 'agreement among raft nodes before linearized reading' (duration: 293.463166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:17.679669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.451062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4783"} {"level":"info","ts":"2026-02-06T20:05:17.679736Z","caller":"traceutil/trace.go:171","msg":"trace[43247880] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:946; }","duration":"359.562825ms","start":"2026-02-06T20:05:17.320156Z","end":"2026-02-06T20:05:17.679719Z","steps":["trace[43247880] 'range keys from in-memory index tree' (duration: 359.273898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:17.679767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:17.320137Z","time spent":"359.623946ms","remote":"127.0.0.1:57226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4806,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-06T20:05:17.680029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.483842ms","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-06T20:05:17.680061Z","caller":"traceutil/trace.go:171","msg":"trace[468039471] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:946; }","duration":"273.535953ms","start":"2026-02-06T20:05:17.406507Z","end":"2026-02-06T20:05:17.680043Z","steps":["trace[468039471] 'range keys from in-memory index tree' (duration: 273.40023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:17.680402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.805209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-06T20:05:17.680422Z","caller":"traceutil/trace.go:171","msg":"trace[1024538247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:946; }","duration":"267.83814ms","start":"2026-02-06T20:05:17.412578Z","end":"2026-02-06T20:05:17.680416Z","steps":["trace[1024538247] 'range keys from in-memory index tree' (duration: 267.603274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:17.680459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.907438ms","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-06T20:05:17.680526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.887921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-06T20:05:17.68054Z","caller":"traceutil/trace.go:171","msg":"trace[1313610303] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:946; }","duration":"269.03054ms","start":"2026-02-06T20:05:17.411491Z","end":"2026-02-06T20:05:17.680522Z","steps":["trace[1313610303] 'range keys from in-memory index tree' (duration: 268.766514ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:05:17.680547Z","caller":"traceutil/trace.go:171","msg":"trace[1736116061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:946; }","duration":"267.916562ms","start":"2026-02-06T20:05:17.412625Z","end":"2026-02-06T20:05:17.680541Z","steps":["trace[1736116061] 'range keys from in-memory index tree' (duration: 267.84711ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:05:18.138794Z","caller":"traceutil/trace.go:171","msg":"trace[305387943] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"451.065874ms","start":"2026-02-06T20:05:17.687699Z","end":"2026-02-06T20:05:18.138764Z","steps":["trace[305387943] 'process raft request' (duration: 450.926901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:18.138914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:17.687678Z","time spent":"451.163497ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:05:18.495128Z","caller":"traceutil/trace.go:171","msg":"trace[1388095697] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"807.231574ms","start":"2026-02-06T20:05:17.687875Z","end":"2026-02-06T20:05:18.495107Z","steps":["trace[1388095697] 'process raft request' (duration: 725.354008ms)","trace[1388095697] 'compare' (duration: 81.502557ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:05:18.495335Z","caller":"traceutil/trace.go:171","msg":"trace[132759364] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:988; }","duration":"728.010575ms","start":"2026-02-06T20:05:17.767316Z","end":"2026-02-06T20:05:18.495326Z","steps":["trace[132759364] 'read index received' (duration: 372.003439ms)","trace[132759364] 'applied index is now lower than readState.Index' (duration: 356.006346ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:05:18.495841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:17.687869Z","time spent":"807.412159ms","remote":"127.0.0.1:57302","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-06T20:05:18.496516Z","caller":"traceutil/trace.go:171","msg":"trace[1899644579] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"808.457866ms","start":"2026-02-06T20:05:17.688001Z","end":"2026-02-06T20:05:18.496459Z","steps":["trace[1899644579] 'process raft request' (duration: 806.923137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:18.496662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:17.687995Z","time spent":"808.586739ms","remote":"127.0.0.1:57302","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-06T20:05:18.496788Z","caller":"traceutil/trace.go:171","msg":"trace[1416151400] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"808.847845ms","start":"2026-02-06T20:05:17.687932Z","end":"2026-02-06T20:05:18.49678Z","steps":["trace[1416151400] 'process raft request' (duration: 807.249944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:18.496839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:17.687927Z","time spent":"808.886566ms","remote":"127.0.0.1:57302","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-06T20:05:18.496934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.638747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T20:05:18.496958Z","caller":"traceutil/trace.go:171","msg":"trace[2053746036] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:950; }","duration":"729.693658ms","start":"2026-02-06T20:05:17.767256Z","end":"2026-02-06T20:05:18.49695Z","steps":["trace[2053746036] 'agreement among raft nodes before linearized reading' (duration: 729.634157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:18.496977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:17.767238Z","time spent":"729.734219ms","remote":"127.0.0.1:57232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-02-06T20:05:18.497121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.048505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:05:18.497145Z","caller":"traceutil/trace.go:171","msg":"trace[351680387] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:950; }","duration":"678.073775ms","start":"2026-02-06T20:05:17.819066Z","end":"2026-02-06T20:05:18.49714Z","steps":["trace[351680387] 'agreement among raft nodes before linearized reading' (duration: 678.036064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:18.497163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:17.819Z","time spent":"678.158437ms","remote":"127.0.0.1:57414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" "} {"level":"warn","ts":"2026-02-06T20:05:18.497326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.760337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:05:18.49735Z","caller":"traceutil/trace.go:171","msg":"trace[1615018108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"490.827658ms","start":"2026-02-06T20:05:18.006516Z","end":"2026-02-06T20:05:18.497343Z","steps":["trace[1615018108] 'agreement among raft nodes before linearized reading' (duration: 490.793607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:18.497368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:18.006501Z","time spent":"490.861399ms","remote":"127.0.0.1:57044","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-06T20:05:19.514255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.325234ms","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:7"} {"level":"info","ts":"2026-02-06T20:05:19.514326Z","caller":"traceutil/trace.go:171","msg":"trace[1705015747] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:950; }","duration":"547.439987ms","start":"2026-02-06T20:05:18.966864Z","end":"2026-02-06T20:05:19.514304Z","steps":["trace[1705015747] 'count revisions from in-memory index tree' (duration: 547.229811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:19.514366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:18.966843Z","time spent":"547.514218ms","remote":"127.0.0.1:39024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-06T20:05:19.514423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.714933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:05:19.514482Z","caller":"traceutil/trace.go:171","msg":"trace[865682962] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:950; }","duration":"816.837226ms","start":"2026-02-06T20:05:18.697626Z","end":"2026-02-06T20:05:19.514464Z","steps":["trace[865682962] 'count revisions from in-memory index tree' (duration: 816.5732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:19.514508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:18.69756Z","time spent":"816.941899ms","remote":"127.0.0.1:57262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-06T20:05:19.514535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.773831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:05:19.514558Z","caller":"traceutil/trace.go:171","msg":"trace[1237894495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"506.827112ms","start":"2026-02-06T20:05:19.007722Z","end":"2026-02-06T20:05:19.514549Z","steps":["trace[1237894495] 'range keys from in-memory index tree' (duration: 506.693079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:19.514574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:19.0077Z","time spent":"506.870383ms","remote":"127.0.0.1:57044","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-06T20:05:19.514741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.443244ms","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-06T20:05:19.514759Z","caller":"traceutil/trace.go:171","msg":"trace[2098071511] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:950; }","duration":"497.461425ms","start":"2026-02-06T20:05:19.017292Z","end":"2026-02-06T20:05:19.514753Z","steps":["trace[2098071511] 'range keys from in-memory index tree' (duration: 497.276871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:19.51478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:19.017243Z","time spent":"497.533386ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-06T20:05:19.514879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.243402ms","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-06T20:05:19.51493Z","caller":"traceutil/trace.go:171","msg":"trace[1889651434] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:950; }","duration":"108.294153ms","start":"2026-02-06T20:05:19.406629Z","end":"2026-02-06T20:05:19.514924Z","steps":["trace[1889651434] 'range keys from in-memory index tree' (duration: 108.235042ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:05:22.671395Z","caller":"traceutil/trace.go:171","msg":"trace[720834565] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"108.059837ms","start":"2026-02-06T20:05:22.563328Z","end":"2026-02-06T20:05:22.671388Z","steps":["trace[720834565] 'process raft request' (duration: 100.25066ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:05:22.671373Z","caller":"traceutil/trace.go:171","msg":"trace[1847723802] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"107.906033ms","start":"2026-02-06T20:05:22.563442Z","end":"2026-02-06T20:05:22.671348Z","steps":["trace[1847723802] 'process raft request' (duration: 107.696838ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:05:23.101291Z","caller":"traceutil/trace.go:171","msg":"trace[1699843280] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"112.756637ms","start":"2026-02-06T20:05:22.988498Z","end":"2026-02-06T20:05:23.101254Z","steps":["trace[1699843280] 'process raft request' (duration: 54.622489ms)","trace[1699843280] 'compare' (duration: 57.569923ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:05:32.734477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.869847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T20:05:32.735396Z","caller":"traceutil/trace.go:171","msg":"trace[2127318706] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1023; }","duration":"107.833292ms","start":"2026-02-06T20:05:32.627536Z","end":"2026-02-06T20:05:32.73537Z","steps":["trace[2127318706] 'count revisions from in-memory index tree' (duration: 106.801886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:32.734691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.417997ms","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-06T20:05:32.735885Z","caller":"traceutil/trace.go:171","msg":"trace[492479088] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1023; }","duration":"489.663249ms","start":"2026-02-06T20:05:32.246199Z","end":"2026-02-06T20:05:32.735862Z","steps":["trace[492479088] 'range keys from in-memory index tree' (duration: 488.045588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:32.735942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:32.246175Z","time spent":"489.750541ms","remote":"127.0.0.1:57302","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-06T20:05:32.734887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.684313ms","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-06T20:05:32.73616Z","caller":"traceutil/trace.go:171","msg":"trace[1968672569] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1023; }","duration":"107.965926ms","start":"2026-02-06T20:05:32.628184Z","end":"2026-02-06T20:05:32.73615Z","steps":["trace[1968672569] 'range keys from in-memory index tree' (duration: 106.622211ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:05:33.392575Z","caller":"traceutil/trace.go:171","msg":"trace[1791378657] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"560.087587ms","start":"2026-02-06T20:05:32.832467Z","end":"2026-02-06T20:05:33.392555Z","steps":["trace[1791378657] 'process raft request' (duration: 559.964014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:33.39355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:32.83245Z","time spent":"561.01752ms","remote":"127.0.0.1:57302","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-06T20:05:33.58609Z","caller":"traceutil/trace.go:171","msg":"trace[301525655] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1070; }","duration":"579.125846ms","start":"2026-02-06T20:05:33.006905Z","end":"2026-02-06T20:05:33.586031Z","steps":["trace[301525655] 'read index received' (duration: 387.928201ms)","trace[301525655] 'applied index is now lower than readState.Index' (duration: 191.196255ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:05:33.586407Z","caller":"traceutil/trace.go:171","msg":"trace[1205580011] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"753.55105ms","start":"2026-02-06T20:05:32.832822Z","end":"2026-02-06T20:05:33.586373Z","steps":["trace[1205580011] 'process raft request' (duration: 751.656152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:33.586684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.764814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:05:33.586725Z","caller":"traceutil/trace.go:171","msg":"trace[1213217285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1027; }","duration":"579.851415ms","start":"2026-02-06T20:05:33.006861Z","end":"2026-02-06T20:05:33.586712Z","steps":["trace[1213217285] 'agreement among raft nodes before linearized reading' (duration: 579.721462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:33.586778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:33.006844Z","time spent":"579.926318ms","remote":"127.0.0.1:57044","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-06T20:05:33.587299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:32.832805Z","time spent":"753.863627ms","remote":"127.0.0.1:57302","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-06T20:05:33.592766Z","caller":"traceutil/trace.go:171","msg":"trace[187924050] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"117.003514ms","start":"2026-02-06T20:05:33.475749Z","end":"2026-02-06T20:05:33.592752Z","steps":["trace[187924050] 'process raft request' (duration: 116.824059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:33.59306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.195978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-06T20:05:33.593092Z","caller":"traceutil/trace.go:171","msg":"trace[556913650] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1028; }","duration":"409.26303ms","start":"2026-02-06T20:05:33.183821Z","end":"2026-02-06T20:05:33.593084Z","steps":["trace[556913650] 'agreement among raft nodes before linearized reading' (duration: 409.174228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:05:33.59316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:05:33.183798Z","time spent":"409.30894ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-06T20:05:34.714638Z","caller":"traceutil/trace.go:171","msg":"trace[772200482] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"116.337045ms","start":"2026-02-06T20:05:34.598258Z","end":"2026-02-06T20:05:34.714595Z","steps":["trace[772200482] 'process raft request' (duration: 116.148491ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:05:50.057039Z","caller":"traceutil/trace.go:171","msg":"trace[733975483] linearizableReadLoop","detail":"{readStateIndex:1160; appliedIndex:1159; }","duration":"112.875138ms","start":"2026-02-06T20:05:49.944132Z","end":"2026-02-06T20:05:50.057007Z","steps":["trace[733975483] 'read index received' (duration: 112.699203ms)","trace[733975483] 'applied index is now lower than readState.Index' (duration: 174.905µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:05:50.057435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.271967ms","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:527"} {"level":"info","ts":"2026-02-06T20:05:50.057556Z","caller":"traceutil/trace.go:171","msg":"trace[157410822] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1113; }","duration":"113.38929ms","start":"2026-02-06T20:05:49.944114Z","end":"2026-02-06T20:05:50.057504Z","steps":["trace[157410822] 'agreement among raft nodes before linearized reading' (duration: 113.115563ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:05:50.057915Z","caller":"traceutil/trace.go:171","msg":"trace[2022599993] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"120.981192ms","start":"2026-02-06T20:05:49.936916Z","end":"2026-02-06T20:05:50.057897Z","steps":["trace[2022599993] 'process raft request' (duration: 119.966787ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:06:35.596316Z","caller":"traceutil/trace.go:171","msg":"trace[570128400] linearizableReadLoop","detail":"{readStateIndex:1355; appliedIndex:1354; }","duration":"144.450695ms","start":"2026-02-06T20:06:35.45115Z","end":"2026-02-06T20:06:35.5956Z","steps":["trace[570128400] 'read index received' (duration: 72.307639ms)","trace[570128400] 'applied index is now lower than readState.Index' (duration: 72.141876ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:06:35.596356Z","caller":"traceutil/trace.go:171","msg":"trace[1056263057] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"172.635987ms","start":"2026-02-06T20:06:35.423512Z","end":"2026-02-06T20:06:35.596148Z","steps":["trace[1056263057] 'process raft request' (duration: 100.0018ms)","trace[1056263057] 'compare' (duration: 71.826397ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:06:35.596709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.549742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.217\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-06T20:06:35.597933Z","caller":"traceutil/trace.go:171","msg":"trace[1402512549] range","detail":"{range_begin:/registry/masterleases/162.253.55.217; range_end:; response_count:1; response_revision:1299; }","duration":"146.754742ms","start":"2026-02-06T20:06:35.451092Z","end":"2026-02-06T20:06:35.597847Z","steps":["trace[1402512549] 'agreement among raft nodes before linearized reading' (duration: 145.312356ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:06:52.595518Z","caller":"traceutil/trace.go:171","msg":"trace[346411708] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"140.770442ms","start":"2026-02-06T20:06:52.453972Z","end":"2026-02-06T20:06:52.594743Z","steps":["trace[346411708] 'process raft request' (duration: 102.015111ms)","trace[346411708] 'compare' (duration: 38.637478ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:07:00.114173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.288054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T20:07:00.114278Z","caller":"traceutil/trace.go:171","msg":"trace[817398351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1397; }","duration":"105.473929ms","start":"2026-02-06T20:07:00.008782Z","end":"2026-02-06T20:07:00.114256Z","steps":["trace[817398351] 'range keys from in-memory index tree' (duration: 105.164361ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:07:10.111404Z","caller":"traceutil/trace.go:171","msg":"trace[1150361768] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"153.885253ms","start":"2026-02-06T20:07:09.957504Z","end":"2026-02-06T20:07:10.11139Z","steps":["trace[1150361768] 'process raft request' (duration: 153.629306ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:07:33.962642Z","caller":"traceutil/trace.go:171","msg":"trace[364829240] transaction","detail":"{read_only:false; response_revision:1534; number_of_response:1; }","duration":"168.872272ms","start":"2026-02-06T20:07:33.793753Z","end":"2026-02-06T20:07:33.962626Z","steps":["trace[364829240] 'process raft request' (duration: 102.387118ms)","trace[364829240] 'compare' (duration: 66.091514ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:08:24.258602Z","caller":"traceutil/trace.go:171","msg":"trace[1567132748] linearizableReadLoop","detail":"{readStateIndex:1817; appliedIndex:1816; }","duration":"131.349435ms","start":"2026-02-06T20:08:24.12721Z","end":"2026-02-06T20:08:24.25856Z","steps":["trace[1567132748] 'read index received' (duration: 131.172391ms)","trace[1567132748] 'applied index is now lower than readState.Index' (duration: 176.164µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:08:24.258872Z","caller":"traceutil/trace.go:171","msg":"trace[1815123386] transaction","detail":"{read_only:false; response_revision:1739; number_of_response:1; }","duration":"155.991315ms","start":"2026-02-06T20:08:24.102857Z","end":"2026-02-06T20:08:24.258848Z","steps":["trace[1815123386] 'process raft request' (duration: 155.588495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:08:24.259252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.986341ms","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-06T20:08:24.259304Z","caller":"traceutil/trace.go:171","msg":"trace[549650569] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1739; }","duration":"132.123694ms","start":"2026-02-06T20:08:24.127168Z","end":"2026-02-06T20:08:24.259291Z","steps":["trace[549650569] 'agreement among raft nodes before linearized reading' (duration: 131.911759ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:08:24.575635Z","caller":"traceutil/trace.go:171","msg":"trace[1765345090] transaction","detail":"{read_only:false; response_revision:1741; number_of_response:1; }","duration":"308.05747ms","start":"2026-02-06T20:08:24.267555Z","end":"2026-02-06T20:08:24.575612Z","steps":["trace[1765345090] 'process raft request' (duration: 285.288868ms)","trace[1765345090] 'compare' (duration: 22.25869ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:08:24.575758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:08:24.267534Z","time spent":"308.160063ms","remote":"127.0.0.1:57302","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-06T20:08:25.041827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.76862ms","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-06T20:08:25.041913Z","caller":"traceutil/trace.go:171","msg":"trace[1465878060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1742; }","duration":"283.890883ms","start":"2026-02-06T20:08:24.758Z","end":"2026-02-06T20:08:25.041891Z","steps":["trace[1465878060] 'range keys from in-memory index tree' (duration: 283.606465ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:08:25.19966Z","caller":"traceutil/trace.go:171","msg":"trace[444451147] transaction","detail":"{read_only:false; response_revision:1743; number_of_response:1; }","duration":"153.465442ms","start":"2026-02-06T20:08:25.046171Z","end":"2026-02-06T20:08:25.199636Z","steps":["trace[444451147] 'process raft request' (duration: 153.332428ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:08:25.45543Z","caller":"traceutil/trace.go:171","msg":"trace[1312651446] transaction","detail":"{read_only:false; response_revision:1744; number_of_response:1; }","duration":"148.29353ms","start":"2026-02-06T20:08:25.307116Z","end":"2026-02-06T20:08:25.455409Z","steps":["trace[1312651446] 'process raft request' (duration: 148.144907ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:08:25.465606Z","caller":"traceutil/trace.go:171","msg":"trace[1856764844] transaction","detail":"{read_only:false; response_revision:1745; number_of_response:1; }","duration":"158.090828ms","start":"2026-02-06T20:08:25.307496Z","end":"2026-02-06T20:08:25.465587Z","steps":["trace[1856764844] 'process raft request' (duration: 157.966705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:08:25.870736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.012412ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-06T20:08:25.870969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:08:25.469797Z","time spent":"401.166165ms","remote":"127.0.0.1:57058","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-06T20:08:25.871133Z","caller":"traceutil/trace.go:171","msg":"trace[1221122122] linearizableReadLoop","detail":"{readStateIndex:1825; appliedIndex:1823; }","duration":"141.751297ms","start":"2026-02-06T20:08:25.729364Z","end":"2026-02-06T20:08:25.871115Z","steps":["trace[1221122122] 'read index received' (duration: 126.162115ms)","trace[1221122122] 'applied index is now lower than readState.Index' (duration: 15.588152ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:08:25.871388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.909151ms","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:527"} {"level":"info","ts":"2026-02-06T20:08:25.871422Z","caller":"traceutil/trace.go:171","msg":"trace[208419089] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1746; }","duration":"142.095216ms","start":"2026-02-06T20:08:25.729318Z","end":"2026-02-06T20:08:25.871414Z","steps":["trace[208419089] 'agreement among raft nodes before linearized reading' (duration: 141.84363ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:08:25.871835Z","caller":"traceutil/trace.go:171","msg":"trace[932281208] transaction","detail":"{read_only:false; response_revision:1746; number_of_response:1; }","duration":"399.890453ms","start":"2026-02-06T20:08:25.471936Z","end":"2026-02-06T20:08:25.871826Z","steps":["trace[932281208] 'process raft request' (duration: 399.069802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:08:25.872006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T20:08:25.471924Z","time spent":"399.937654ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T20:08:26.016576Z","caller":"traceutil/trace.go:171","msg":"trace[35442192] linearizableReadLoop","detail":"{readStateIndex:1827; appliedIndex:1826; }","duration":"137.257213ms","start":"2026-02-06T20:08:25.879195Z","end":"2026-02-06T20:08:26.016452Z","steps":["trace[35442192] 'read index received' (duration: 97.677208ms)","trace[35442192] 'applied index is now lower than readState.Index' (duration: 39.579035ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:08:26.016694Z","caller":"traceutil/trace.go:171","msg":"trace[1869097086] transaction","detail":"{read_only:false; response_revision:1748; number_of_response:1; }","duration":"139.793257ms","start":"2026-02-06T20:08:25.87687Z","end":"2026-02-06T20:08:26.016663Z","steps":["trace[1869097086] 'process raft request' (duration: 100.091279ms)","trace[1869097086] 'compare' (duration: 39.339729ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T20:08:26.016789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.580611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-06T20:08:26.01686Z","caller":"traceutil/trace.go:171","msg":"trace[17131725] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1748; }","duration":"137.674034ms","start":"2026-02-06T20:08:25.879172Z","end":"2026-02-06T20:08:26.016846Z","steps":["trace[17131725] 'agreement among raft nodes before linearized reading' (duration: 137.571571ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:08:54.579469Z","caller":"traceutil/trace.go:171","msg":"trace[1554384487] linearizableReadLoop","detail":"{readStateIndex:1977; appliedIndex:1976; }","duration":"194.222596ms","start":"2026-02-06T20:08:54.385216Z","end":"2026-02-06T20:08:54.579439Z","steps":["trace[1554384487] 'read index received' (duration: 194.088893ms)","trace[1554384487] 'applied index is now lower than readState.Index' (duration: 133.003µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T20:08:54.580175Z","caller":"traceutil/trace.go:171","msg":"trace[1350701066] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"259.702563ms","start":"2026-02-06T20:08:54.320461Z","end":"2026-02-06T20:08:54.580163Z","steps":["trace[1350701066] 'process raft request' (duration: 258.889663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T20:08:54.580589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.368086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T20:08:54.580702Z","caller":"traceutil/trace.go:171","msg":"trace[365373059] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1892; }","duration":"195.52345ms","start":"2026-02-06T20:08:54.385168Z","end":"2026-02-06T20:08:54.580691Z","steps":["trace[365373059] 'agreement among raft nodes before linearized reading' (duration: 195.379227ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T20:08:54.830287Z","caller":"traceutil/trace.go:171","msg":"trace[1014275681] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"237.723872ms","start":"2026-02-06T20:08:54.592536Z","end":"2026-02-06T20:08:54.83026Z","steps":["trace[1014275681] 'process raft request' (duration: 200.858024ms)","trace[1014275681] 'compare' (duration: 35.928084ms)"],"step_count":2}