{"level":"warn","ts":"2026-02-24T18:55:44.948658Z","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-24T18:55:44.948736Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132: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-24T18:55:44.948797Z","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-24T18:55:44.948804Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-02-24T18:55:44.948823Z","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-24T18:55:44.949565Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-02-24T18:55:44.949643Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132: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-24T18:55:44.955631Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.077202ms"} {"level":"info","ts":"2026-02-24T18:55:44.961697Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-02-24T18:55:44.961766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T18:55:44.961787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-02-24T18:55:44.9618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T18:55:44.961812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-02-24T18:55:44.961846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-02-24T18:55:44.966184Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T18:55:44.96779Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T18:55:44.968941Z","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-24T18:55:44.97212Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T18:55:44.973056Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T18:55:44.973127Z","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-24T18:55:44.973267Z","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-24T18:55:44.973296Z","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-24T18:55:44.977465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-02-24T18:55:44.977783Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-02-24T18:55:44.988247Z","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-24T18:55:44.98847Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T18:55:44.988496Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T18:55:44.988543Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-02-24T18:55:44.988551Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-02-24T18:55:45.662727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T18:55:45.662791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T18:55:45.662831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-02-24T18:55:45.662851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-02-24T18:55:45.662872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-02-24T18:55:45.662895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-02-24T18:55:45.662918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-02-24T18:55:45.66375Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T18:55:45.664054Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T18:55:45.664928Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T18:55:45.665444Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T18:55:45.665624Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T18:55:45.665733Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T18:55:45.665764Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T18:55:45.665858Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T18:55:45.665941Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T18:55:45.668182Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T18:55:45.674793Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-02-24T18:55:48.42188Z","caller":"traceutil/trace.go:171","msg":"trace[2026088554] transaction","detail":"{read_only:false; response_revision:145; number_of_response:1; }","duration":"170.934238ms","start":"2026-02-24T18:55:48.250911Z","end":"2026-02-24T18:55:48.421845Z","steps":["trace[2026088554] 'process raft request' (duration: 143.280108ms)","trace[2026088554] 'compare' (duration: 27.465175ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T18:56:13.510344Z","caller":"traceutil/trace.go:171","msg":"trace[358939048] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"154.620964ms","start":"2026-02-24T18:56:13.355702Z","end":"2026-02-24T18:56:13.510323Z","steps":["trace[358939048] 'process raft request' (duration: 154.45644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:56:14.145105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.812027ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T18:56:14.670779Z","caller":"traceutil/trace.go:171","msg":"trace[1835712772] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"191.274234ms","start":"2026-02-24T18:56:14.479468Z","end":"2026-02-24T18:56:14.670742Z","steps":["trace[1835712772] 'process raft request' (duration: 190.629326ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:56:15.82086Z","caller":"traceutil/trace.go:171","msg":"trace[794981853] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:561; }","duration":"192.150679ms","start":"2026-02-24T18:56:15.628692Z","end":"2026-02-24T18:56:15.820842Z","steps":["trace[794981853] 'read index received' (duration: 191.983905ms)","trace[794981853] 'applied index is now lower than readState.Index' (duration: 166.184µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T18:56:15.820942Z","caller":"traceutil/trace.go:171","msg":"trace[2010522888] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"216.058764ms","start":"2026-02-24T18:56:15.604848Z","end":"2026-02-24T18:56:15.820906Z","steps":["trace[2010522888] 'process raft request' (duration: 215.882389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:56:15.82103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.319624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T18:56:15.821058Z","caller":"traceutil/trace.go:171","msg":"trace[273743574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:543; }","duration":"192.397466ms","start":"2026-02-24T18:56:15.628652Z","end":"2026-02-24T18:56:15.82105Z","steps":["trace[273743574] 'agreement among raft nodes before linearized reading' (duration: 192.292703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:56:15.82111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.234375ms","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-24T18:56:15.82123Z","caller":"traceutil/trace.go:171","msg":"trace[843123484] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:543; }","duration":"130.390169ms","start":"2026-02-24T18:56:15.690813Z","end":"2026-02-24T18:56:15.821203Z","steps":["trace[843123484] 'agreement among raft nodes before linearized reading' (duration: 130.220635ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:56:16.107473Z","caller":"traceutil/trace.go:171","msg":"trace[332587024] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:562; }","duration":"213.336949ms","start":"2026-02-24T18:56:15.894118Z","end":"2026-02-24T18:56:16.107455Z","steps":["trace[332587024] 'read index received' (duration: 211.196419ms)","trace[332587024] 'applied index is now lower than readState.Index' (duration: 2.13975ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:56:16.107756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.546044ms","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-24T18:56:16.107803Z","caller":"traceutil/trace.go:171","msg":"trace[2097843507] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"281.796454ms","start":"2026-02-24T18:56:15.825964Z","end":"2026-02-24T18:56:16.10776Z","steps":["trace[2097843507] 'process raft request' (duration: 279.366876ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:56:16.107865Z","caller":"traceutil/trace.go:171","msg":"trace[339177253] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:544; }","duration":"213.731649ms","start":"2026-02-24T18:56:15.894111Z","end":"2026-02-24T18:56:16.107842Z","steps":["trace[339177253] 'agreement among raft nodes before linearized reading' (duration: 213.464412ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:56:18.639384Z","caller":"traceutil/trace.go:171","msg":"trace[1280140710] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"126.902343ms","start":"2026-02-24T18:56:18.512459Z","end":"2026-02-24T18:56:18.639361Z","steps":["trace[1280140710] 'process raft request' (duration: 123.665682ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:56:18.644504Z","caller":"traceutil/trace.go:171","msg":"trace[361837518] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"113.628963ms","start":"2026-02-24T18:56:18.530848Z","end":"2026-02-24T18:56:18.644477Z","steps":["trace[361837518] 'process raft request' (duration: 113.377686ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:56:22.394677Z","caller":"traceutil/trace.go:171","msg":"trace[1862969367] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"261.200791ms","start":"2026-02-24T18:56:22.133447Z","end":"2026-02-24T18:56:22.394648Z","steps":["trace[1862969367] 'process raft request' (duration: 260.977365ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:56:22.682885Z","caller":"traceutil/trace.go:171","msg":"trace[918722195] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"231.241527ms","start":"2026-02-24T18:56:22.451615Z","end":"2026-02-24T18:56:22.682857Z","steps":["trace[918722195] 'process raft request' (duration: 231.076443ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:56:23.048933Z","caller":"traceutil/trace.go:171","msg":"trace[1123249242] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"112.201464ms","start":"2026-02-24T18:56:22.936705Z","end":"2026-02-24T18:56:23.048907Z","steps":["trace[1123249242] 'read index received' (duration: 112.031309ms)","trace[1123249242] 'applied index is now lower than readState.Index' (duration: 168.605µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T18:56:23.049038Z","caller":"traceutil/trace.go:171","msg":"trace[1344100275] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"117.240563ms","start":"2026-02-24T18:56:22.931759Z","end":"2026-02-24T18:56:23.049Z","steps":["trace[1344100275] 'process raft request' (duration: 116.976316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:56:23.04924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.516762ms","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-24T18:56:23.049289Z","caller":"traceutil/trace.go:171","msg":"trace[1521072417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:584; }","duration":"112.648936ms","start":"2026-02-24T18:56:22.936628Z","end":"2026-02-24T18:56:23.049277Z","steps":["trace[1521072417] 'agreement among raft nodes before linearized reading' (duration: 112.380649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:56:24.766206Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256877325375718909,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T18:56:24.791992Z","caller":"traceutil/trace.go:171","msg":"trace[822704344] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"938.445741ms","start":"2026-02-24T18:56:23.85352Z","end":"2026-02-24T18:56:24.791966Z","steps":["trace[822704344] 'process raft request' (duration: 938.277627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:56:24.7928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:56:23.853495Z","time spent":"938.569335ms","remote":"127.0.0.1:49502","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-24T18:56:24.795074Z","caller":"traceutil/trace.go:171","msg":"trace[419819243] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:606; }","duration":"529.534139ms","start":"2026-02-24T18:56:24.265514Z","end":"2026-02-24T18:56:24.795048Z","steps":["trace[419819243] 'read index received' (duration: 526.33604ms)","trace[419819243] 'applied index is now lower than readState.Index' (duration: 3.196789ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:56:24.795205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.669553ms","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-24T18:56:24.795248Z","caller":"traceutil/trace.go:171","msg":"trace[205307559] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:587; }","duration":"529.728555ms","start":"2026-02-24T18:56:24.265508Z","end":"2026-02-24T18:56:24.795236Z","steps":["trace[205307559] 'agreement among raft nodes before linearized reading' (duration: 529.645332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:56:24.795282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:56:24.265452Z","time spent":"529.821398ms","remote":"127.0.0.1:41848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-24T18:56:24.795337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.732885ms","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-24T18:56:24.795424Z","caller":"traceutil/trace.go:171","msg":"trace[304671855] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:587; }","duration":"529.845448ms","start":"2026-02-24T18:56:24.265548Z","end":"2026-02-24T18:56:24.795393Z","steps":["trace[304671855] 'agreement among raft nodes before linearized reading' (duration: 529.679713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:56:24.795476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:56:24.265544Z","time spent":"529.92062ms","remote":"127.0.0.1:41848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-24T18:56:24.795625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.559739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T18:56:24.795673Z","caller":"traceutil/trace.go:171","msg":"trace[2116385213] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:587; }","duration":"483.641711ms","start":"2026-02-24T18:56:24.312018Z","end":"2026-02-24T18:56:24.795659Z","steps":["trace[2116385213] 'agreement among raft nodes before linearized reading' (duration: 483.559709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:56:24.795713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:56:24.312007Z","time spent":"483.693293ms","remote":"127.0.0.1:41866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"warn","ts":"2026-02-24T18:56:24.795743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.911784ms","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":"warn","ts":"2026-02-24T18:56:24.795799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.781571ms","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":"warn","ts":"2026-02-24T18:56:24.795823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.275863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T18:56:24.795886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.87404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T18:56:24.7959Z","caller":"traceutil/trace.go:171","msg":"trace[238967006] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:587; }","duration":"473.384697ms","start":"2026-02-24T18:56:24.322498Z","end":"2026-02-24T18:56:24.795882Z","steps":["trace[238967006] 'agreement among raft nodes before linearized reading' (duration: 473.253143ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:56:24.795899Z","caller":"traceutil/trace.go:171","msg":"trace[250018869] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:587; }","duration":"513.900844ms","start":"2026-02-24T18:56:24.281967Z","end":"2026-02-24T18:56:24.795868Z","steps":["trace[250018869] 'agreement among raft nodes before linearized reading' (duration: 513.721159ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:56:24.795933Z","caller":"traceutil/trace.go:171","msg":"trace[1158190989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"182.954803ms","start":"2026-02-24T18:56:24.612962Z","end":"2026-02-24T18:56:24.795917Z","steps":["trace[1158190989] 'agreement among raft nodes before linearized reading' (duration: 182.86012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:56:24.795956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:56:24.322489Z","time spent":"473.446838ms","remote":"127.0.0.1:41880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-02-24T18:56:24.795971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:56:24.281952Z","time spent":"514.000527ms","remote":"127.0.0.1:41860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-24T18:56:24.796011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.533956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-24T18:56:24.796077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.291332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T18:56:24.796128Z","caller":"traceutil/trace.go:171","msg":"trace[1931194123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:587; }","duration":"397.64393ms","start":"2026-02-24T18:56:24.398413Z","end":"2026-02-24T18:56:24.796057Z","steps":["trace[1931194123] 'agreement among raft nodes before linearized reading' (duration: 397.436594ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:56:24.796149Z","caller":"traceutil/trace.go:171","msg":"trace[1566905787] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:587; }","duration":"497.394495ms","start":"2026-02-24T18:56:24.298732Z","end":"2026-02-24T18:56:24.796127Z","steps":["trace[1566905787] 'agreement among raft nodes before linearized reading' (duration: 497.270561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:56:24.796176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:56:24.398385Z","time spent":"397.779013ms","remote":"127.0.0.1:49502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T18:56:24.79619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:56:24.298716Z","time spent":"497.465297ms","remote":"127.0.0.1:41866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"warn","ts":"2026-02-24T18:56:24.796267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.924425ms","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":"warn","ts":"2026-02-24T18:56:24.796287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.411616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T18:56:24.796308Z","caller":"traceutil/trace.go:171","msg":"trace[1601876344] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:587; }","duration":"513.971496ms","start":"2026-02-24T18:56:24.282322Z","end":"2026-02-24T18:56:24.796293Z","steps":["trace[1601876344] 'agreement among raft nodes before linearized reading' (duration: 513.915625ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:56:24.79632Z","caller":"traceutil/trace.go:171","msg":"trace[750339435] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:587; }","duration":"497.483148ms","start":"2026-02-24T18:56:24.298827Z","end":"2026-02-24T18:56:24.79631Z","steps":["trace[750339435] 'agreement among raft nodes before linearized reading' (duration: 497.428206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:56:24.796322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.277881ms","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":"warn","ts":"2026-02-24T18:56:24.796344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:56:24.282318Z","time spent":"514.016497ms","remote":"127.0.0.1:41860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-02-24T18:56:24.796352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:56:24.298811Z","time spent":"497.532229ms","remote":"127.0.0.1:41866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-02-24T18:56:24.796375Z","caller":"traceutil/trace.go:171","msg":"trace[1889404367] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:587; }","duration":"474.346113ms","start":"2026-02-24T18:56:24.322011Z","end":"2026-02-24T18:56:24.796357Z","steps":["trace[1889404367] 'agreement among raft nodes before linearized reading' (duration: 474.263321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:56:24.796413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.415325ms","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":"warn","ts":"2026-02-24T18:56:24.796416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:56:24.322005Z","time spent":"474.401364ms","remote":"127.0.0.1:41880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-02-24T18:56:24.796482Z","caller":"traceutil/trace.go:171","msg":"trace[299272969] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:587; }","duration":"474.469716ms","start":"2026-02-24T18:56:24.321967Z","end":"2026-02-24T18:56:24.796436Z","steps":["trace[299272969] 'agreement among raft nodes before linearized reading' (duration: 474.419445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:56:24.797029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:56:24.321953Z","time spent":"475.060413ms","remote":"127.0.0.1:41880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"info","ts":"2026-02-24T18:56:24.795777Z","caller":"traceutil/trace.go:171","msg":"trace[318274230] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:587; }","duration":"513.962376ms","start":"2026-02-24T18:56:24.281805Z","end":"2026-02-24T18:56:24.795767Z","steps":["trace[318274230] 'agreement among raft nodes before linearized reading' (duration: 513.903385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:56:24.797191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:56:24.281797Z","time spent":"515.380526ms","remote":"127.0.0.1:41860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-02-24T18:56:24.796563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.770904ms","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-24T18:56:24.797368Z","caller":"traceutil/trace.go:171","msg":"trace[280469471] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:587; }","duration":"531.576467ms","start":"2026-02-24T18:56:24.265781Z","end":"2026-02-24T18:56:24.797357Z","steps":["trace[280469471] 'agreement among raft nodes before linearized reading' (duration: 530.642511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:56:24.797404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:56:24.265776Z","time spent":"531.615588ms","remote":"127.0.0.1:41848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-02-24T18:56:29.801467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.81259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T18:56:29.801557Z","caller":"traceutil/trace.go:171","msg":"trace[1253466557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"154.950303ms","start":"2026-02-24T18:56:29.646588Z","end":"2026-02-24T18:56:29.801539Z","steps":["trace[1253466557] 'range keys from in-memory index tree' (duration: 154.622934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:56:43.294572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.034702ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T18:56:43.294715Z","caller":"traceutil/trace.go:171","msg":"trace[1814224019] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:750; }","duration":"151.204735ms","start":"2026-02-24T18:56:43.143489Z","end":"2026-02-24T18:56:43.294694Z","steps":["trace[1814224019] 'read index received' (duration: 24.905366ms)","trace[1814224019] 'applied index is now lower than readState.Index' (duration: 126.297789ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T18:56:43.294803Z","caller":"traceutil/trace.go:171","msg":"trace[1894570455] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"375.879795ms","start":"2026-02-24T18:56:42.918873Z","end":"2026-02-24T18:56:43.294753Z","steps":["trace[1894570455] 'process raft request' (duration: 249.529904ms)","trace[1894570455] 'compare' (duration: 125.899489ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:56:43.295021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:56:42.918855Z","time spent":"376.069271ms","remote":"127.0.0.1:49502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T18:56:43.295012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.505114ms","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-24T18:56:43.295088Z","caller":"traceutil/trace.go:171","msg":"trace[510683278] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:725; }","duration":"151.625978ms","start":"2026-02-24T18:56:43.143441Z","end":"2026-02-24T18:56:43.295067Z","steps":["trace[510683278] 'agreement among raft nodes before linearized reading' (duration: 151.36134ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:56:43.496548Z","caller":"traceutil/trace.go:171","msg":"trace[1493518102] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"197.733947ms","start":"2026-02-24T18:56:43.298788Z","end":"2026-02-24T18:56:43.496522Z","steps":["trace[1493518102] 'process raft request' (duration: 193.642185ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:56:43.497552Z","caller":"traceutil/trace.go:171","msg":"trace[601099049] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"196.812382ms","start":"2026-02-24T18:56:43.300724Z","end":"2026-02-24T18:56:43.497536Z","steps":["trace[601099049] 'process raft request' (duration: 196.448532ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:56:45.82739Z","caller":"traceutil/trace.go:171","msg":"trace[406167908] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:756; }","duration":"528.253953ms","start":"2026-02-24T18:56:45.299103Z","end":"2026-02-24T18:56:45.827357Z","steps":["trace[406167908] 'read index received' (duration: 441.373409ms)","trace[406167908] 'applied index is now lower than readState.Index' (duration: 86.878304ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T18:56:45.827437Z","caller":"traceutil/trace.go:171","msg":"trace[1886993964] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"812.973436ms","start":"2026-02-24T18:56:45.014399Z","end":"2026-02-24T18:56:45.827373Z","steps":["trace[1886993964] 'process raft request' (duration: 726.153564ms)","trace[1886993964] 'compare' (duration: 86.601946ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:56:45.827635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:56:45.014377Z","time spent":"813.169862ms","remote":"127.0.0.1:49316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":815,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T18:56:45.827665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.552262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-24T18:56:45.82769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.140633ms","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-24T18:56:45.827721Z","caller":"traceutil/trace.go:171","msg":"trace[1640368957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:730; }","duration":"528.653085ms","start":"2026-02-24T18:56:45.299053Z","end":"2026-02-24T18:56:45.827706Z","steps":["trace[1640368957] 'agreement among raft nodes before linearized reading' (duration: 528.400258ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:56:45.827741Z","caller":"traceutil/trace.go:171","msg":"trace[1143729527] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:730; }","duration":"327.243745ms","start":"2026-02-24T18:56:45.500481Z","end":"2026-02-24T18:56:45.827725Z","steps":["trace[1143729527] 'agreement among raft nodes before linearized reading' (duration: 327.0725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:56:45.82776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:56:45.299034Z","time spent":"528.715756ms","remote":"127.0.0.1:49502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T18:56:45.827786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:56:45.500466Z","time spent":"327.307967ms","remote":"127.0.0.1:49502","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-24T18:56:45.827947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.513688ms","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-24T18:56:45.828004Z","caller":"traceutil/trace.go:171","msg":"trace[1157851979] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:730; }","duration":"325.598591ms","start":"2026-02-24T18:56:45.502394Z","end":"2026-02-24T18:56:45.827993Z","steps":["trace[1157851979] 'agreement among raft nodes before linearized reading' (duration: 325.491118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:56:45.828031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:56:45.502377Z","time spent":"325.647611ms","remote":"127.0.0.1:49502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-24T18:56:46.173073Z","caller":"traceutil/trace.go:171","msg":"trace[1056833567] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"339.78302ms","start":"2026-02-24T18:56:45.833267Z","end":"2026-02-24T18:56:46.17305Z","steps":["trace[1056833567] 'process raft request' (duration: 262.649905ms)","trace[1056833567] 'compare' (duration: 76.442626ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T18:56:46.173178Z","caller":"traceutil/trace.go:171","msg":"trace[534258902] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:758; }","duration":"278.129443ms","start":"2026-02-24T18:56:45.895028Z","end":"2026-02-24T18:56:46.173157Z","steps":["trace[534258902] 'read index received' (duration: 200.922216ms)","trace[534258902] 'applied index is now lower than readState.Index' (duration: 77.206057ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:56:46.173205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:56:45.833242Z","time spent":"339.898253ms","remote":"127.0.0.1:49502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T18:56:46.173367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.334408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T18:56:46.173462Z","caller":"traceutil/trace.go:171","msg":"trace[643521185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:733; }","duration":"278.463382ms","start":"2026-02-24T18:56:45.894984Z","end":"2026-02-24T18:56:46.173447Z","steps":["trace[643521185] 'agreement among raft nodes before linearized reading' (duration: 278.239955ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:56:46.173605Z","caller":"traceutil/trace.go:171","msg":"trace[133691604] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"335.457791ms","start":"2026-02-24T18:56:45.838136Z","end":"2026-02-24T18:56:46.173593Z","steps":["trace[133691604] 'process raft request' (duration: 334.872314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:56:46.173771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:56:45.838127Z","time spent":"335.600324ms","remote":"127.0.0.1:49502","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-24T18:56:46.396036Z","caller":"traceutil/trace.go:171","msg":"trace[1239494907] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"219.066895ms","start":"2026-02-24T18:56:46.176946Z","end":"2026-02-24T18:56:46.396013Z","steps":["trace[1239494907] 'process raft request' (duration: 218.942742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:05.086455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.104624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T18:57:05.086545Z","caller":"traceutil/trace.go:171","msg":"trace[2095269096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"473.246458ms","start":"2026-02-24T18:57:04.613283Z","end":"2026-02-24T18:57:05.086529Z","steps":["trace[2095269096] 'range keys from in-memory index tree' (duration: 473.023462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:05.086581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:04.613266Z","time spent":"473.307649ms","remote":"127.0.0.1:49250","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-24T18:57:05.452346Z","caller":"traceutil/trace.go:171","msg":"trace[1754729551] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"208.053962ms","start":"2026-02-24T18:57:05.244254Z","end":"2026-02-24T18:57:05.452308Z","steps":["trace[1754729551] 'read index received' (duration: 205.377629ms)","trace[1754729551] 'applied index is now lower than readState.Index' (duration: 2.674403ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:57:05.452617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.318159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T18:57:05.452668Z","caller":"traceutil/trace.go:171","msg":"trace[1076672134] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:792; }","duration":"208.432073ms","start":"2026-02-24T18:57:05.24422Z","end":"2026-02-24T18:57:05.452652Z","steps":["trace[1076672134] 'agreement among raft nodes before linearized reading' (duration: 208.301179ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:57:05.452677Z","caller":"traceutil/trace.go:171","msg":"trace[993952388] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"363.008601ms","start":"2026-02-24T18:57:05.089623Z","end":"2026-02-24T18:57:05.452632Z","steps":["trace[993952388] 'process raft request' (duration: 360.089361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:05.452939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:05.089583Z","time spent":"363.206547ms","remote":"127.0.0.1:49502","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-24T18:57:05.819975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.314154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T18:57:05.820112Z","caller":"traceutil/trace.go:171","msg":"trace[1548424174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"206.512839ms","start":"2026-02-24T18:57:05.613561Z","end":"2026-02-24T18:57:05.820074Z","steps":["trace[1548424174] 'range keys from in-memory index tree' (duration: 206.205541ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:57:06.691732Z","caller":"traceutil/trace.go:171","msg":"trace[318986947] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"108.18306ms","start":"2026-02-24T18:57:06.583518Z","end":"2026-02-24T18:57:06.691701Z","steps":["trace[318986947] 'process raft request' (duration: 107.609174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:07.331994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.792626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T18:57:07.332084Z","caller":"traceutil/trace.go:171","msg":"trace[10182621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:796; }","duration":"145.92707ms","start":"2026-02-24T18:57:07.186134Z","end":"2026-02-24T18:57:07.332061Z","steps":["trace[10182621] 'range keys from in-memory index tree' (duration: 145.703225ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:57:07.760507Z","caller":"traceutil/trace.go:171","msg":"trace[2118153245] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"147.502294ms","start":"2026-02-24T18:57:07.612987Z","end":"2026-02-24T18:57:07.76049Z","steps":["trace[2118153245] 'read index received' (duration: 147.425202ms)","trace[2118153245] 'applied index is now lower than readState.Index' (duration: 76.442µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T18:57:07.760588Z","caller":"traceutil/trace.go:171","msg":"trace[1829512626] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"300.293272ms","start":"2026-02-24T18:57:07.46029Z","end":"2026-02-24T18:57:07.760583Z","steps":["trace[1829512626] 'process raft request' (duration: 300.082887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:07.760661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:07.460266Z","time spent":"300.338304ms","remote":"127.0.0.1:49502","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-24T18:57:07.760721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.909587ms","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":"warn","ts":"2026-02-24T18:57:07.760746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.74607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T18:57:07.76094Z","caller":"traceutil/trace.go:171","msg":"trace[2039897380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"147.993817ms","start":"2026-02-24T18:57:07.612931Z","end":"2026-02-24T18:57:07.760925Z","steps":["trace[2039897380] 'agreement among raft nodes before linearized reading' (duration: 147.7389ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:57:07.760811Z","caller":"traceutil/trace.go:171","msg":"trace[433001746] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:797; }","duration":"131.059591ms","start":"2026-02-24T18:57:07.629728Z","end":"2026-02-24T18:57:07.760788Z","steps":["trace[433001746] 'agreement among raft nodes before linearized reading' (duration: 130.889656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:08.383528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.185681ms","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-24T18:57:08.383612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.176584ms","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-24T18:57:08.383624Z","caller":"traceutil/trace.go:171","msg":"trace[261752330] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:797; }","duration":"418.342286ms","start":"2026-02-24T18:57:07.965259Z","end":"2026-02-24T18:57:08.383601Z","steps":["trace[261752330] 'range keys from in-memory index tree' (duration: 417.907573ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:57:08.383677Z","caller":"traceutil/trace.go:171","msg":"trace[1532129235] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:797; }","duration":"417.278126ms","start":"2026-02-24T18:57:07.966379Z","end":"2026-02-24T18:57:08.383657Z","steps":["trace[1532129235] 'count revisions from in-memory index tree' (duration: 417.118492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:08.383678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:07.965244Z","time spent":"418.420717ms","remote":"127.0.0.1:49502","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-24T18:57:08.383723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:07.966372Z","time spent":"417.336827ms","remote":"127.0.0.1:49484","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-24T18:57:08.699844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.786959ms","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-24T18:57:08.69993Z","caller":"traceutil/trace.go:171","msg":"trace[596405954] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:800; }","duration":"185.925242ms","start":"2026-02-24T18:57:08.513982Z","end":"2026-02-24T18:57:08.699908Z","steps":["trace[596405954] 'count revisions from in-memory index tree' (duration: 185.716946ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:57:08.70085Z","caller":"traceutil/trace.go:171","msg":"trace[13998652] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"125.782775ms","start":"2026-02-24T18:57:08.575043Z","end":"2026-02-24T18:57:08.700826Z","steps":["trace[13998652] 'process raft request' (duration: 94.77082ms)","trace[13998652] 'compare' (duration: 30.505831ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T18:57:08.959055Z","caller":"traceutil/trace.go:171","msg":"trace[2049764024] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"165.809547ms","start":"2026-02-24T18:57:08.7932Z","end":"2026-02-24T18:57:08.959009Z","steps":["trace[2049764024] 'process raft request' (duration: 165.288293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:21.114482Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256877325375719561,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T18:57:21.252533Z","caller":"traceutil/trace.go:171","msg":"trace[2017941095] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"781.085279ms","start":"2026-02-24T18:57:20.471417Z","end":"2026-02-24T18:57:21.252502Z","steps":["trace[2017941095] 'process raft request' (duration: 780.906894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:21.2527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:20.471401Z","time spent":"781.220312ms","remote":"127.0.0.1:49502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T18:57:21.663837Z","caller":"traceutil/trace.go:171","msg":"trace[1891090846] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"1.190518998s","start":"2026-02-24T18:57:20.4733Z","end":"2026-02-24T18:57:21.663819Z","steps":["trace[1891090846] 'process raft request' (duration: 1.190175548s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:21.663956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:20.473283Z","time spent":"1.19060806s","remote":"127.0.0.1:49502","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-24T18:57:21.663985Z","caller":"traceutil/trace.go:171","msg":"trace[467130721] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:920; }","duration":"1.050313584s","start":"2026-02-24T18:57:20.613647Z","end":"2026-02-24T18:57:21.663961Z","steps":["trace[467130721] 'read index received' (duration: 638.897631ms)","trace[467130721] 'applied index is now lower than readState.Index' (duration: 411.414973ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T18:57:21.664137Z","caller":"traceutil/trace.go:171","msg":"trace[1406158566] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"1.190294621s","start":"2026-02-24T18:57:20.473832Z","end":"2026-02-24T18:57:21.664127Z","steps":["trace[1406158566] 'process raft request' (duration: 1.189950732s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:21.664261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:20.473823Z","time spent":"1.190372424s","remote":"127.0.0.1:49502","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-24T18:57:21.664347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.965252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T18:57:21.664375Z","caller":"traceutil/trace.go:171","msg":"trace[1066680697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:886; }","duration":"884.024724ms","start":"2026-02-24T18:57:20.780341Z","end":"2026-02-24T18:57:21.664366Z","steps":["trace[1066680697] 'agreement among raft nodes before linearized reading' (duration: 883.935811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:21.664395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:20.780319Z","time spent":"884.071744ms","remote":"127.0.0.1:49502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T18:57:21.664542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050893831s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T18:57:21.664561Z","caller":"traceutil/trace.go:171","msg":"trace[1901562405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"1.050938062s","start":"2026-02-24T18:57:20.613617Z","end":"2026-02-24T18:57:21.664555Z","steps":["trace[1901562405] 'agreement among raft nodes before linearized reading' (duration: 1.050907371s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:21.664577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:20.613601Z","time spent":"1.050973483s","remote":"127.0.0.1:49250","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-24T18:57:21.664679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.693786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T18:57:21.664697Z","caller":"traceutil/trace.go:171","msg":"trace[990912146] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:886; }","duration":"499.740937ms","start":"2026-02-24T18:57:21.164951Z","end":"2026-02-24T18:57:21.664692Z","steps":["trace[990912146] 'agreement among raft nodes before linearized reading' (duration: 499.708056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:21.664725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:21.164931Z","time spent":"499.788318ms","remote":"127.0.0.1:49510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-24T18:57:35.537678Z","caller":"traceutil/trace.go:171","msg":"trace[2051062361] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"200.549626ms","start":"2026-02-24T18:57:35.337096Z","end":"2026-02-24T18:57:35.537645Z","steps":["trace[2051062361] 'process raft request' (duration: 200.381861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:40.029111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:39.498962Z","time spent":"530.139075ms","remote":"127.0.0.1:44018","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-24T18:57:40.029238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.605371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T18:57:40.029914Z","caller":"traceutil/trace.go:171","msg":"trace[1331554035] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:942; }","duration":"693.29901ms","start":"2026-02-24T18:57:39.336533Z","end":"2026-02-24T18:57:40.029832Z","steps":["trace[1331554035] 'count revisions from in-memory index tree' (duration: 692.466177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:40.02999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:39.336513Z","time spent":"693.446473ms","remote":"127.0.0.1:49490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-02-24T18:57:40.030465Z","caller":"traceutil/trace.go:171","msg":"trace[1828322425] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:982; }","duration":"480.744923ms","start":"2026-02-24T18:57:39.549691Z","end":"2026-02-24T18:57:40.030436Z","steps":["trace[1828322425] 'read index received' (duration: 480.485966ms)","trace[1828322425] 'applied index is now lower than readState.Index' (duration: 257.597µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T18:57:40.030541Z","caller":"traceutil/trace.go:171","msg":"trace[1441240835] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"498.515044ms","start":"2026-02-24T18:57:39.532001Z","end":"2026-02-24T18:57:40.030516Z","steps":["trace[1441240835] 'process raft request' (duration: 498.237286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:40.030679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:39.531972Z","time spent":"498.631277ms","remote":"127.0.0.1:49502","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-24T18:57:40.030797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.094923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T18:57:40.03086Z","caller":"traceutil/trace.go:171","msg":"trace[1356702617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:943; }","duration":"481.30485ms","start":"2026-02-24T18:57:39.549538Z","end":"2026-02-24T18:57:40.030843Z","steps":["trace[1356702617] 'agreement among raft nodes before linearized reading' (duration: 481.100704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:40.030891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.309902ms","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-24T18:57:40.030916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:39.549523Z","time spent":"481.379771ms","remote":"127.0.0.1:49502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T18:57:40.030938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.579527ms","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-24T18:57:40.030996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.719071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T18:57:40.031026Z","caller":"traceutil/trace.go:171","msg":"trace[1956146012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:943; }","duration":"253.801632ms","start":"2026-02-24T18:57:39.777217Z","end":"2026-02-24T18:57:40.031019Z","steps":["trace[1956146012] 'agreement among raft nodes before linearized reading' (duration: 253.73003ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:57:40.03093Z","caller":"traceutil/trace.go:171","msg":"trace[515406690] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:943; }","duration":"256.386884ms","start":"2026-02-24T18:57:39.774532Z","end":"2026-02-24T18:57:40.030919Z","steps":["trace[515406690] 'agreement among raft nodes before linearized reading' (duration: 256.25905ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:57:40.030992Z","caller":"traceutil/trace.go:171","msg":"trace[233354671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:943; }","duration":"253.673139ms","start":"2026-02-24T18:57:39.777305Z","end":"2026-02-24T18:57:40.030979Z","steps":["trace[233354671] 'agreement among raft nodes before linearized reading' (duration: 253.537195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:40.031078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.694563ms","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-24T18:57:40.031113Z","caller":"traceutil/trace.go:171","msg":"trace[1901650750] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:943; }","duration":"121.765035ms","start":"2026-02-24T18:57:39.90934Z","end":"2026-02-24T18:57:40.031105Z","steps":["trace[1901650750] 'agreement among raft nodes before linearized reading' (duration: 121.672642ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:57:40.740673Z","caller":"traceutil/trace.go:171","msg":"trace[1853810954] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"705.617169ms","start":"2026-02-24T18:57:40.035025Z","end":"2026-02-24T18:57:40.740642Z","steps":["trace[1853810954] 'process raft request' (duration: 705.525006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:40.740776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:40.035007Z","time spent":"705.716912ms","remote":"127.0.0.1:49502","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-24T18:57:40.89924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.367916ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T18:57:40.899598Z","caller":"traceutil/trace.go:171","msg":"trace[1763231456] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"864.117105ms","start":"2026-02-24T18:57:40.035458Z","end":"2026-02-24T18:57:40.899575Z","steps":["trace[1763231456] 'process raft request' (duration: 751.338668ms)","trace[1763231456] 'compare' (duration: 112.086538ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:57:40.899716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:40.035441Z","time spent":"864.211388ms","remote":"127.0.0.1:49502","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-24T18:57:40.899858Z","caller":"traceutil/trace.go:171","msg":"trace[901137188] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"864.360032ms","start":"2026-02-24T18:57:40.035473Z","end":"2026-02-24T18:57:40.899833Z","steps":["trace[901137188] 'process raft request' (duration: 863.947831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:40.900021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:40.03545Z","time spent":"864.517497ms","remote":"127.0.0.1:49502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T18:57:40.900156Z","caller":"traceutil/trace.go:171","msg":"trace[1034104277] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"864.365152ms","start":"2026-02-24T18:57:40.035782Z","end":"2026-02-24T18:57:40.900148Z","steps":["trace[1034104277] 'process raft request' (duration: 863.826577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:40.900206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:40.035767Z","time spent":"864.414284ms","remote":"127.0.0.1:49502","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-24T18:57:40.900426Z","caller":"traceutil/trace.go:171","msg":"trace[2017567414] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:983; }","duration":"656.169197ms","start":"2026-02-24T18:57:40.244248Z","end":"2026-02-24T18:57:40.900417Z","steps":["trace[2017567414] 'read index received' (duration: 496.254931ms)","trace[2017567414] 'applied index is now lower than readState.Index' (duration: 159.913586ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T18:57:40.900521Z","caller":"traceutil/trace.go:171","msg":"trace[1071924562] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"864.715472ms","start":"2026-02-24T18:57:40.035798Z","end":"2026-02-24T18:57:40.900514Z","steps":["trace[1071924562] 'process raft request' (duration: 864.305311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:40.900559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:40.035787Z","time spent":"864.750253ms","remote":"127.0.0.1:49502","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-24T18:57:40.900652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.409314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T18:57:40.900677Z","caller":"traceutil/trace.go:171","msg":"trace[1237574332] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:948; }","duration":"656.472095ms","start":"2026-02-24T18:57:40.244197Z","end":"2026-02-24T18:57:40.900669Z","steps":["trace[1237574332] 'agreement among raft nodes before linearized reading' (duration: 656.408004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:40.900696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:40.244179Z","time spent":"656.511076ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-02-24T18:57:40.900688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.874824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T18:57:40.900809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.109525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T18:57:40.900861Z","caller":"traceutil/trace.go:171","msg":"trace[890633723] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:948; }","duration":"285.186126ms","start":"2026-02-24T18:57:40.615662Z","end":"2026-02-24T18:57:40.900848Z","steps":["trace[890633723] 'agreement among raft nodes before linearized reading' (duration: 285.114684ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:57:40.900941Z","caller":"traceutil/trace.go:171","msg":"trace[1336110684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:948; }","duration":"286.926155ms","start":"2026-02-24T18:57:40.613798Z","end":"2026-02-24T18:57:40.900724Z","steps":["trace[1336110684] 'agreement among raft nodes before linearized reading' (duration: 286.847143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:41.739054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.882556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T18:57:41.739148Z","caller":"traceutil/trace.go:171","msg":"trace[1164183467] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:949; }","duration":"241.01628ms","start":"2026-02-24T18:57:41.498112Z","end":"2026-02-24T18:57:41.739129Z","steps":["trace[1164183467] 'range keys from in-memory index tree' (duration: 240.809894ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:57:41.921527Z","caller":"traceutil/trace.go:171","msg":"trace[2092705413] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"175.010871ms","start":"2026-02-24T18:57:41.746476Z","end":"2026-02-24T18:57:41.921487Z","steps":["trace[2092705413] 'process raft request' (duration: 115.523582ms)","trace[2092705413] 'compare' (duration: 59.273363ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:57:42.906236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.486398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T18:57:42.906329Z","caller":"traceutil/trace.go:171","msg":"trace[1211381534] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"292.837448ms","start":"2026-02-24T18:57:42.613478Z","end":"2026-02-24T18:57:42.906315Z","steps":["trace[1211381534] 'read index received' (duration: 24.881µs)","trace[1211381534] 'applied index is now lower than readState.Index' (duration: 292.811537ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T18:57:42.906499Z","caller":"traceutil/trace.go:171","msg":"trace[655079346] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"977.704256ms","start":"2026-02-24T18:57:41.928783Z","end":"2026-02-24T18:57:42.906487Z","steps":["trace[655079346] 'process raft request' (duration: 141.897489ms)","trace[655079346] 'compare' (duration: 834.943843ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:57:42.906561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:41.928759Z","time spent":"977.773418ms","remote":"127.0.0.1:49502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T18:57:42.906677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.196708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T18:57:42.90671Z","caller":"traceutil/trace.go:171","msg":"trace[1563654809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:954; }","duration":"293.249899ms","start":"2026-02-24T18:57:42.61345Z","end":"2026-02-24T18:57:42.9067Z","steps":["trace[1563654809] 'agreement among raft nodes before linearized reading' (duration: 293.201388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:42.90688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.142701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T18:57:42.906916Z","caller":"traceutil/trace.go:171","msg":"trace[1007503364] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:954; }","duration":"201.205143ms","start":"2026-02-24T18:57:42.705703Z","end":"2026-02-24T18:57:42.906908Z","steps":["trace[1007503364] 'agreement among raft nodes before linearized reading' (duration: 201.147232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:42.907314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.122716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T18:57:42.907436Z","caller":"traceutil/trace.go:171","msg":"trace[1830086873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:954; }","duration":"162.194698ms","start":"2026-02-24T18:57:42.745149Z","end":"2026-02-24T18:57:42.907344Z","steps":["trace[1830086873] 'agreement among raft nodes before linearized reading' (duration: 162.100836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:43.682086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.302214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T18:57:43.682335Z","caller":"traceutil/trace.go:171","msg":"trace[109585468] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"769.55683ms","start":"2026-02-24T18:57:42.912758Z","end":"2026-02-24T18:57:43.682315Z","steps":["trace[109585468] 'process raft request' (duration: 664.922188ms)","trace[109585468] 'compare' (duration: 103.82468ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:57:43.682392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:42.91274Z","time spent":"769.626452ms","remote":"127.0.0.1:49502","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-24T18:57:43.720127Z","caller":"traceutil/trace.go:171","msg":"trace[2109369019] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"806.872778ms","start":"2026-02-24T18:57:42.913208Z","end":"2026-02-24T18:57:43.720081Z","steps":["trace[2109369019] 'process raft request' (duration: 806.638651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:43.720372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:42.913194Z","time spent":"807.028053ms","remote":"127.0.0.1:49502","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-24T18:57:43.720698Z","caller":"traceutil/trace.go:171","msg":"trace[40649466] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"807.411523ms","start":"2026-02-24T18:57:42.913269Z","end":"2026-02-24T18:57:43.72068Z","steps":["trace[40649466] 'process raft request' (duration: 806.750245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:43.720792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:42.913263Z","time spent":"807.483795ms","remote":"127.0.0.1:49502","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-24T18:57:43.721083Z","caller":"traceutil/trace.go:171","msg":"trace[427325473] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"807.814904ms","start":"2026-02-24T18:57:42.913246Z","end":"2026-02-24T18:57:43.721061Z","steps":["trace[427325473] 'process raft request' (duration: 807.367761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:43.721178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:42.913212Z","time spent":"807.921427ms","remote":"127.0.0.1:49502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T18:57:43.721437Z","caller":"traceutil/trace.go:171","msg":"trace[1012500908] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"807.760962ms","start":"2026-02-24T18:57:42.913647Z","end":"2026-02-24T18:57:43.721408Z","steps":["trace[1012500908] 'process raft request' (duration: 807.33633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:43.721655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:42.91364Z","time spent":"807.908626ms","remote":"127.0.0.1:49502","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-24T18:57:43.721757Z","caller":"traceutil/trace.go:171","msg":"trace[1078790823] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:994; }","duration":"440.765572ms","start":"2026-02-24T18:57:43.280974Z","end":"2026-02-24T18:57:43.721739Z","steps":["trace[1078790823] 'read index received' (duration: 296.702023ms)","trace[1078790823] 'applied index is now lower than readState.Index' (duration: 144.062189ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:57:43.721925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.953338ms","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-24T18:57:43.72196Z","caller":"traceutil/trace.go:171","msg":"trace[718434757] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"569.554601ms","start":"2026-02-24T18:57:43.152393Z","end":"2026-02-24T18:57:43.721947Z","steps":["trace[718434757] 'process raft request' (duration: 568.926064ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:57:43.721983Z","caller":"traceutil/trace.go:171","msg":"trace[670596797] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:960; }","duration":"441.060891ms","start":"2026-02-24T18:57:43.280907Z","end":"2026-02-24T18:57:43.721968Z","steps":["trace[670596797] 'agreement among raft nodes before linearized reading' (duration: 440.906566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:43.722036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:43.280872Z","time spent":"441.150653ms","remote":"127.0.0.1:49644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":30,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-24T18:57:43.722047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:43.152369Z","time spent":"569.622372ms","remote":"127.0.0.1:49502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T18:57:44.616758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.71384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T18:57:44.616844Z","caller":"traceutil/trace.go:171","msg":"trace[111864962] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:960; }","duration":"612.864775ms","start":"2026-02-24T18:57:44.003964Z","end":"2026-02-24T18:57:44.616828Z","steps":["trace[111864962] 'count revisions from in-memory index tree' (duration: 612.588027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:44.616881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:44.003922Z","time spent":"612.951177ms","remote":"127.0.0.1:49464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-24T18:57:44.99508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.522342ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T18:57:44.995201Z","caller":"traceutil/trace.go:171","msg":"trace[1022489854] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1000; }","duration":"383.064604ms","start":"2026-02-24T18:57:44.612113Z","end":"2026-02-24T18:57:44.995178Z","steps":["trace[1022489854] 'read index received' (duration: 34.481µs)","trace[1022489854] 'applied index is now lower than readState.Index' (duration: 383.028363ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:57:44.995417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.28461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T18:57:44.995452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.337805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T18:57:44.995515Z","caller":"traceutil/trace.go:171","msg":"trace[568715228] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:960; }","duration":"327.473989ms","start":"2026-02-24T18:57:44.668023Z","end":"2026-02-24T18:57:44.995497Z","steps":["trace[568715228] 'agreement among raft nodes before linearized reading' (duration: 327.363076ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:57:44.995513Z","caller":"traceutil/trace.go:171","msg":"trace[88565944] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"383.421033ms","start":"2026-02-24T18:57:44.612072Z","end":"2026-02-24T18:57:44.995493Z","steps":["trace[88565944] 'agreement among raft nodes before linearized reading' (duration: 383.216128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:44.995586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:44.668006Z","time spent":"327.568662ms","remote":"127.0.0.1:41794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-02-24T18:57:44.995608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:44.612054Z","time spent":"383.538457ms","remote":"127.0.0.1:49250","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-24T18:57:44.995452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.130015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T18:57:44.995798Z","caller":"traceutil/trace.go:171","msg":"trace[525571548] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:960; }","duration":"229.493965ms","start":"2026-02-24T18:57:44.766292Z","end":"2026-02-24T18:57:44.995785Z","steps":["trace[525571548] 'agreement among raft nodes before linearized reading' (duration: 229.108935ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:57:46.092348Z","caller":"traceutil/trace.go:171","msg":"trace[1805270827] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"366.160178ms","start":"2026-02-24T18:57:45.726161Z","end":"2026-02-24T18:57:46.092321Z","steps":["trace[1805270827] 'read index received' (duration: 366.081076ms)","trace[1805270827] 'applied index is now lower than readState.Index' (duration: 78.032µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T18:57:46.092393Z","caller":"traceutil/trace.go:171","msg":"trace[341493703] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"401.69593ms","start":"2026-02-24T18:57:45.690653Z","end":"2026-02-24T18:57:46.092349Z","steps":["trace[341493703] 'process raft request' (duration: 401.472544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:46.092564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.400505ms","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-24T18:57:46.092635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.188639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T18:57:46.092659Z","caller":"traceutil/trace.go:171","msg":"trace[428235203] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:961; }","duration":"366.536919ms","start":"2026-02-24T18:57:45.726107Z","end":"2026-02-24T18:57:46.092644Z","steps":["trace[428235203] 'agreement among raft nodes before linearized reading' (duration: 366.285422ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:57:46.092675Z","caller":"traceutil/trace.go:171","msg":"trace[1124809933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:961; }","duration":"366.285071ms","start":"2026-02-24T18:57:45.726378Z","end":"2026-02-24T18:57:46.092663Z","steps":["trace[1124809933] 'agreement among raft nodes before linearized reading' (duration: 366.115207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:46.092545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:45.690628Z","time spent":"401.844913ms","remote":"127.0.0.1:49502","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-24T18:57:46.092701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:45.726085Z","time spent":"366.6084ms","remote":"127.0.0.1:49502","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-24T18:57:46.092716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:45.72636Z","time spent":"366.347134ms","remote":"127.0.0.1:49502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T18:57:46.092796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.775377ms","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-24T18:57:46.092858Z","caller":"traceutil/trace.go:171","msg":"trace[1368338452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:961; }","duration":"365.87668ms","start":"2026-02-24T18:57:45.726966Z","end":"2026-02-24T18:57:46.092843Z","steps":["trace[1368338452] 'agreement among raft nodes before linearized reading' (duration: 365.680745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:46.092892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:45.726952Z","time spent":"365.934292ms","remote":"127.0.0.1:49502","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-24T18:57:46.093579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.838199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T18:57:46.093672Z","caller":"traceutil/trace.go:171","msg":"trace[1033717272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:961; }","duration":"366.639521ms","start":"2026-02-24T18:57:45.726994Z","end":"2026-02-24T18:57:46.093634Z","steps":["trace[1033717272] 'agreement among raft nodes before linearized reading' (duration: 365.631543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:46.093711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:45.726967Z","time spent":"366.731164ms","remote":"127.0.0.1:49502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T18:57:46.495116Z","caller":"traceutil/trace.go:171","msg":"trace[1431185301] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1002; }","duration":"325.946927ms","start":"2026-02-24T18:57:46.169145Z","end":"2026-02-24T18:57:46.495092Z","steps":["trace[1431185301] 'read index received' (duration: 267.520114ms)","trace[1431185301] 'applied index is now lower than readState.Index' (duration: 58.425703ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:57:46.495389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.213294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T18:57:46.495484Z","caller":"traceutil/trace.go:171","msg":"trace[208321349] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:965; }","duration":"326.400229ms","start":"2026-02-24T18:57:46.16907Z","end":"2026-02-24T18:57:46.49547Z","steps":["trace[208321349] 'agreement among raft nodes before linearized reading' (duration: 326.208114ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:57:46.495521Z","caller":"traceutil/trace.go:171","msg":"trace[1397458560] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"397.828771ms","start":"2026-02-24T18:57:46.097656Z","end":"2026-02-24T18:57:46.495484Z","steps":["trace[1397458560] 'process raft request' (duration: 397.40203ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:57:46.495465Z","caller":"traceutil/trace.go:171","msg":"trace[1590637237] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"397.844161ms","start":"2026-02-24T18:57:46.097597Z","end":"2026-02-24T18:57:46.495441Z","steps":["trace[1590637237] 'process raft request' (duration: 397.42299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:46.495578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:46.169051Z","time spent":"326.513223ms","remote":"127.0.0.1:49428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-02-24T18:57:46.495404Z","caller":"traceutil/trace.go:171","msg":"trace[1530406914] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"398.116979ms","start":"2026-02-24T18:57:46.097238Z","end":"2026-02-24T18:57:46.495355Z","steps":["trace[1530406914] 'process raft request' (duration: 397.683207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:46.495728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:46.097648Z","time spent":"397.991046ms","remote":"127.0.0.1:49502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T18:57:46.495907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:46.097588Z","time spent":"398.230582ms","remote":"127.0.0.1:49502","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-24T18:57:46.496122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:46.097221Z","time spent":"398.84313ms","remote":"127.0.0.1:49502","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-24T18:57:46.495401Z","caller":"traceutil/trace.go:171","msg":"trace[742377427] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"398.959742ms","start":"2026-02-24T18:57:46.096404Z","end":"2026-02-24T18:57:46.495364Z","steps":["trace[742377427] 'process raft request' (duration: 340.249121ms)","trace[742377427] 'compare' (duration: 58.090014ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:57:46.496243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:46.096385Z","time spent":"399.825537ms","remote":"127.0.0.1:49502","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-24T18:57:47.15929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.864119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T18:57:47.159668Z","caller":"traceutil/trace.go:171","msg":"trace[1917576947] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:965; }","duration":"383.310362ms","start":"2026-02-24T18:57:46.77634Z","end":"2026-02-24T18:57:47.15965Z","steps":["trace[1917576947] 'count revisions from in-memory index tree' (duration: 382.736156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:47.159806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:46.776319Z","time spent":"383.469875ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-02-24T18:57:47.159334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.86479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T18:57:47.160151Z","caller":"traceutil/trace.go:171","msg":"trace[615882376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"545.724134ms","start":"2026-02-24T18:57:46.614403Z","end":"2026-02-24T18:57:47.160127Z","steps":["trace[615882376] 'range keys from in-memory index tree' (duration: 544.778638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:47.160225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:46.614381Z","time spent":"545.821207ms","remote":"127.0.0.1:49250","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-24T18:57:47.160108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.521349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T18:57:47.160356Z","caller":"traceutil/trace.go:171","msg":"trace[1271112746] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:965; }","duration":"349.804697ms","start":"2026-02-24T18:57:46.810538Z","end":"2026-02-24T18:57:47.160343Z","steps":["trace[1271112746] 'count revisions from in-memory index tree' (duration: 349.451556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:47.160501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:46.81052Z","time spent":"349.914239ms","remote":"127.0.0.1:49316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":119,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-24T18:57:50.938722Z","caller":"traceutil/trace.go:171","msg":"trace[1593710655] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1035; }","duration":"425.059923ms","start":"2026-02-24T18:57:50.513632Z","end":"2026-02-24T18:57:50.938692Z","steps":["trace[1593710655] 'read index received' (duration: 424.828257ms)","trace[1593710655] 'applied index is now lower than readState.Index' (duration: 230.316µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:57:50.93894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.278539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T18:57:50.938978Z","caller":"traceutil/trace.go:171","msg":"trace[759138454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:994; }","duration":"425.370162ms","start":"2026-02-24T18:57:50.513596Z","end":"2026-02-24T18:57:50.938966Z","steps":["trace[759138454] 'agreement among raft nodes before linearized reading' (duration: 425.204397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:50.939017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:50.513575Z","time spent":"425.433063ms","remote":"127.0.0.1:49502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-24T18:57:50.939113Z","caller":"traceutil/trace.go:171","msg":"trace[1275233647] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"425.585948ms","start":"2026-02-24T18:57:50.5135Z","end":"2026-02-24T18:57:50.939086Z","steps":["trace[1275233647] 'process raft request' (duration: 424.962131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:50.939281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:50.513487Z","time spent":"425.698431ms","remote":"127.0.0.1:49502","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-24T18:57:51.223165Z","caller":"traceutil/trace.go:171","msg":"trace[1624176753] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"706.7978ms","start":"2026-02-24T18:57:50.516345Z","end":"2026-02-24T18:57:51.223142Z","steps":["trace[1624176753] 'process raft request' (duration: 706.754719ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:57:51.223219Z","caller":"traceutil/trace.go:171","msg":"trace[487801912] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"708.054745ms","start":"2026-02-24T18:57:50.51513Z","end":"2026-02-24T18:57:51.223184Z","steps":["trace[487801912] 'process raft request' (duration: 707.837499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:51.223377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:50.515104Z","time spent":"708.184469ms","remote":"127.0.0.1:49502","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-24T18:57:51.223511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:50.516337Z","time spent":"706.899653ms","remote":"127.0.0.1:49502","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-24T18:57:51.27326Z","caller":"traceutil/trace.go:171","msg":"trace[2118261121] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"330.160364ms","start":"2026-02-24T18:57:50.943074Z","end":"2026-02-24T18:57:51.273234Z","steps":["trace[2118261121] 'process raft request' (duration: 330.025491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:51.273425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:50.943052Z","time spent":"330.290657ms","remote":"127.0.0.1:49502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T18:57:54.334463Z","caller":"traceutil/trace.go:171","msg":"trace[733393039] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"214.458789ms","start":"2026-02-24T18:57:54.11998Z","end":"2026-02-24T18:57:54.334439Z","steps":["trace[733393039] 'read index received' (duration: 211.714813ms)","trace[733393039] 'applied index is now lower than readState.Index' (duration: 2.742496ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:57:54.334851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.84827ms","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-24T18:57:54.334953Z","caller":"traceutil/trace.go:171","msg":"trace[1050959674] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1012; }","duration":"214.936043ms","start":"2026-02-24T18:57:54.119974Z","end":"2026-02-24T18:57:54.33491Z","steps":["trace[1050959674] 'agreement among raft nodes before linearized reading' (duration: 214.542802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:56.891728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.48377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T18:57:56.891849Z","caller":"traceutil/trace.go:171","msg":"trace[180851247] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1065; }","duration":"547.122662ms","start":"2026-02-24T18:57:56.344703Z","end":"2026-02-24T18:57:56.891826Z","steps":["trace[180851247] 'read index received' (duration: 98.502248ms)","trace[180851247] 'applied index is now lower than readState.Index' (duration: 448.619004ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T18:57:56.892044Z","caller":"traceutil/trace.go:171","msg":"trace[1586952361] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"639.893254ms","start":"2026-02-24T18:57:56.252101Z","end":"2026-02-24T18:57:56.891994Z","steps":["trace[1586952361] 'process raft request' (duration: 191.083425ms)","trace[1586952361] 'compare' (duration: 448.214652ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:57:56.892224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:56.252085Z","time spent":"640.021237ms","remote":"127.0.0.1:49502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T18:57:56.892333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.623607ms","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-24T18:57:56.892378Z","caller":"traceutil/trace.go:171","msg":"trace[1921909143] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1023; }","duration":"547.708349ms","start":"2026-02-24T18:57:56.344659Z","end":"2026-02-24T18:57:56.892368Z","steps":["trace[1921909143] 'agreement among raft nodes before linearized reading' (duration: 547.216486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:57:56.892418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:57:56.34464Z","time spent":"547.76973ms","remote":"127.0.0.1:49502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T18:58:43.659739Z","caller":"traceutil/trace.go:171","msg":"trace[142154172] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"105.244676ms","start":"2026-02-24T18:58:43.554471Z","end":"2026-02-24T18:58:43.659716Z","steps":["trace[142154172] 'process raft request' (duration: 28.772515ms)","trace[142154172] 'compare' (duration: 76.386868ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:59:01.814971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.788464ms","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-24T18:59:01.81505Z","caller":"traceutil/trace.go:171","msg":"trace[1314495486] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1302; }","duration":"103.906717ms","start":"2026-02-24T18:59:01.711127Z","end":"2026-02-24T18:59:01.815034Z","steps":["trace[1314495486] 'range keys from in-memory index tree' (duration: 103.689541ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:59:01.956994Z","caller":"traceutil/trace.go:171","msg":"trace[934387451] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"136.417237ms","start":"2026-02-24T18:59:01.820525Z","end":"2026-02-24T18:59:01.956943Z","steps":["trace[934387451] 'process raft request' (duration: 94.991772ms)","trace[934387451] 'compare' (duration: 41.155128ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T18:59:01.95742Z","caller":"traceutil/trace.go:171","msg":"trace[1405489709] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"136.664143ms","start":"2026-02-24T18:59:01.820709Z","end":"2026-02-24T18:59:01.957373Z","steps":["trace[1405489709] 'process raft request' (duration: 136.16903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:59:01.957613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.266316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T18:59:01.957687Z","caller":"traceutil/trace.go:171","msg":"trace[1181788525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1305; }","duration":"100.490932ms","start":"2026-02-24T18:59:01.857179Z","end":"2026-02-24T18:59:01.95767Z","steps":["trace[1181788525] 'agreement among raft nodes before linearized reading' (duration: 99.954198ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:01:07.788059Z","caller":"traceutil/trace.go:171","msg":"trace[1245360552] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"316.234365ms","start":"2026-02-24T19:01:07.471792Z","end":"2026-02-24T19:01:07.788026Z","steps":["trace[1245360552] 'process raft request' (duration: 315.968257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:01:07.788248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:01:07.471776Z","time spent":"316.373968ms","remote":"127.0.0.1:49502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}