{"level":"warn","ts":"2026-04-08T17:06:16.965803Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T17:06:16.965891Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.168: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.19.213.168:2380","--initial-cluster=instance=https://199.19.213.168:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.168:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.168: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-04-08T17:06:16.965964Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T17:06:16.965981Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.168:2380"]} {"level":"info","ts":"2026-04-08T17:06:16.966012Z","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-04-08T17:06:16.966662Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.168:2379"]} {"level":"info","ts":"2026-04-08T17:06:16.966744Z","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":16,"max-cpu-available":16,"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.19.213.168:2380"],"listen-peer-urls":["https://199.19.213.168:2380"],"advertise-client-urls":["https://199.19.213.168:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.168:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.168: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-04-08T17:06:16.971511Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.47562ms"} {"level":"info","ts":"2026-04-08T17:06:16.977255Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8ae391decac9b376","cluster-id":"d9b32c15a2c66ac"} {"level":"info","ts":"2026-04-08T17:06:16.977349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T17:06:16.977383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 became follower at term 0"} {"level":"info","ts":"2026-04-08T17:06:16.977416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8ae391decac9b376 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T17:06:16.977428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 became follower at term 1"} {"level":"info","ts":"2026-04-08T17:06:16.977484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 switched to configuration voters=(10008003183018357622)"} {"level":"warn","ts":"2026-04-08T17:06:16.982964Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T17:06:16.985028Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T17:06:16.986036Z","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-04-08T17:06:16.988523Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8ae391decac9b376","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T17:06:16.988739Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8ae391decac9b376","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T17:06:16.988812Z","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-04-08T17:06:16.989007Z","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-04-08T17:06:16.989087Z","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-04-08T17:06:16.990241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 switched to configuration voters=(10008003183018357622)"} {"level":"info","ts":"2026-04-08T17:06:16.991073Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d9b32c15a2c66ac","local-member-id":"8ae391decac9b376","added-peer-id":"8ae391decac9b376","added-peer-peer-urls":["https://199.19.213.168:2380"]} {"level":"info","ts":"2026-04-08T17:06:16.993025Z","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-04-08T17:06:16.993173Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.168:2380"} {"level":"info","ts":"2026-04-08T17:06:16.993234Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.168:2380"} {"level":"info","ts":"2026-04-08T17:06:16.99332Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8ae391decac9b376","initial-advertise-peer-urls":["https://199.19.213.168:2380"],"listen-peer-urls":["https://199.19.213.168:2380"],"advertise-client-urls":["https://199.19.213.168:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.168:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T17:06:16.99337Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T17:06:17.278135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T17:06:17.278202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T17:06:17.27822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 received MsgPreVoteResp from 8ae391decac9b376 at term 1"} {"level":"info","ts":"2026-04-08T17:06:17.278235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 became candidate at term 2"} {"level":"info","ts":"2026-04-08T17:06:17.278243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 received MsgVoteResp from 8ae391decac9b376 at term 2"} {"level":"info","ts":"2026-04-08T17:06:17.278254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 became leader at term 2"} {"level":"info","ts":"2026-04-08T17:06:17.278264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8ae391decac9b376 elected leader 8ae391decac9b376 at term 2"} {"level":"info","ts":"2026-04-08T17:06:17.279012Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T17:06:17.279841Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T17:06:17.27983Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8ae391decac9b376","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.168:2379]}","request-path":"/0/members/8ae391decac9b376/attributes","cluster-id":"d9b32c15a2c66ac","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T17:06:17.279971Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T17:06:17.280214Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T17:06:17.280307Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T17:06:17.281012Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d9b32c15a2c66ac","local-member-id":"8ae391decac9b376","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T17:06:17.281116Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T17:06:17.28115Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T17:06:17.283623Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T17:06:17.284227Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.168:2379"} {"level":"info","ts":"2026-04-08T17:06:56.047286Z","caller":"traceutil/trace.go:171","msg":"trace[734075114] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"151.240028ms","start":"2026-04-08T17:06:55.896018Z","end":"2026-04-08T17:06:56.047258Z","steps":["trace[734075114] 'process raft request' (duration: 150.995353ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:06:56.283381Z","caller":"traceutil/trace.go:171","msg":"trace[1769120746] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"206.529183ms","start":"2026-04-08T17:06:56.07681Z","end":"2026-04-08T17:06:56.283339Z","steps":["trace[1769120746] 'read index received' (duration: 206.202476ms)","trace[1769120746] 'applied index is now lower than readState.Index' (duration: 325.127µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T17:06:56.28365Z","caller":"traceutil/trace.go:171","msg":"trace[1072198729] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"216.543767ms","start":"2026-04-08T17:06:56.067024Z","end":"2026-04-08T17:06:56.283568Z","steps":["trace[1072198729] 'process raft request' (duration: 216.074797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:06:56.283927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.084596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-08T17:06:56.284044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.286268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T17:06:56.284091Z","caller":"traceutil/trace.go:171","msg":"trace[1535185325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"207.317842ms","start":"2026-04-08T17:06:56.076743Z","end":"2026-04-08T17:06:56.284061Z","steps":["trace[1535185325] 'agreement among raft nodes before linearized reading' (duration: 206.882331ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:06:56.284153Z","caller":"traceutil/trace.go:171","msg":"trace[1492751285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"180.397341ms","start":"2026-04-08T17:06:56.103703Z","end":"2026-04-08T17:06:56.2841Z","steps":["trace[1492751285] 'agreement among raft nodes before linearized reading' (duration: 179.93608ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:06:56.44842Z","caller":"traceutil/trace.go:171","msg":"trace[1535376061] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"160.127467ms","start":"2026-04-08T17:06:56.288264Z","end":"2026-04-08T17:06:56.448392Z","steps":["trace[1535376061] 'process raft request' (duration: 86.885141ms)","trace[1535376061] 'compare' (duration: 73.108933ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T17:06:56.465907Z","caller":"traceutil/trace.go:171","msg":"trace[2094914839] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"177.584328ms","start":"2026-04-08T17:06:56.288297Z","end":"2026-04-08T17:06:56.465882Z","steps":["trace[2094914839] 'process raft request' (duration: 177.325111ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:06:56.46594Z","caller":"traceutil/trace.go:171","msg":"trace[1761879973] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"176.980695ms","start":"2026-04-08T17:06:56.288931Z","end":"2026-04-08T17:06:56.465912Z","steps":["trace[1761879973] 'process raft request' (duration: 176.842101ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:06:56.466102Z","caller":"traceutil/trace.go:171","msg":"trace[251149641] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"177.135867ms","start":"2026-04-08T17:06:56.288951Z","end":"2026-04-08T17:06:56.466087Z","steps":["trace[251149641] 'process raft request' (duration: 176.902191ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:06:56.466181Z","caller":"traceutil/trace.go:171","msg":"trace[1912667588] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"175.412339ms","start":"2026-04-08T17:06:56.290727Z","end":"2026-04-08T17:06:56.46614Z","steps":["trace[1912667588] 'process raft request' (duration: 175.229495ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:06:56.466123Z","caller":"traceutil/trace.go:171","msg":"trace[203374088] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"174.232413ms","start":"2026-04-08T17:06:56.291871Z","end":"2026-04-08T17:06:56.466103Z","steps":["trace[203374088] 'process raft request' (duration: 174.183542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:06:56.767957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.054913ms","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-04-08T17:06:56.768034Z","caller":"traceutil/trace.go:171","msg":"trace[387895441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"210.186095ms","start":"2026-04-08T17:06:56.55783Z","end":"2026-04-08T17:06:56.768017Z","steps":["trace[387895441] 'range keys from in-memory index tree' (duration: 209.92448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:06:56.768026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.504006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mw88s\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-08T17:06:56.768105Z","caller":"traceutil/trace.go:171","msg":"trace[1882660057] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mw88s; range_end:; response_count:1; response_revision:630; }","duration":"216.63476ms","start":"2026-04-08T17:06:56.551451Z","end":"2026-04-08T17:06:56.768086Z","steps":["trace[1882660057] 'range keys from in-memory index tree' (duration: 216.337733ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:06:56.950148Z","caller":"traceutil/trace.go:171","msg":"trace[990663218] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"115.159132ms","start":"2026-04-08T17:06:56.834966Z","end":"2026-04-08T17:06:56.950125Z","steps":["trace[990663218] 'read index received' (duration: 114.919577ms)","trace[990663218] 'applied index is now lower than readState.Index' (duration: 238.655µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T17:06:56.95021Z","caller":"traceutil/trace.go:171","msg":"trace[1415839909] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"165.717311ms","start":"2026-04-08T17:06:56.78445Z","end":"2026-04-08T17:06:56.950167Z","steps":["trace[1415839909] 'process raft request' (duration: 165.497696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:06:56.95033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.340256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T17:06:56.95037Z","caller":"traceutil/trace.go:171","msg":"trace[841988871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"115.399807ms","start":"2026-04-08T17:06:56.834961Z","end":"2026-04-08T17:06:56.950361Z","steps":["trace[841988871] 'agreement among raft nodes before linearized reading' (duration: 115.288885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:08:31.027596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.671167ms","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-04-08T17:08:31.027684Z","caller":"traceutil/trace.go:171","msg":"trace[934234783] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1858; }","duration":"211.80717ms","start":"2026-04-08T17:08:30.815856Z","end":"2026-04-08T17:08:31.027663Z","steps":["trace[934234783] 'range keys from in-memory index tree' (duration: 211.533023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:08:31.027685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.675752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2f3edfa8-c427-4825-b07b-35f2858d0ef1\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-08T17:08:31.027744Z","caller":"traceutil/trace.go:171","msg":"trace[1671719127] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2f3edfa8-c427-4825-b07b-35f2858d0ef1; range_end:; response_count:1; response_revision:1858; }","duration":"200.778705ms","start":"2026-04-08T17:08:30.826952Z","end":"2026-04-08T17:08:31.027731Z","steps":["trace[1671719127] 'range keys from in-memory index tree' (duration: 200.56134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:08:31.027593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.746503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T17:08:31.027883Z","caller":"traceutil/trace.go:171","msg":"trace[2001319417] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1858; }","duration":"306.0623ms","start":"2026-04-08T17:08:30.721774Z","end":"2026-04-08T17:08:31.027837Z","steps":["trace[2001319417] 'count revisions from in-memory index tree' (duration: 305.603889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:08:31.02802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T17:08:30.721753Z","time spent":"306.241084ms","remote":"127.0.0.1:41398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-04-08T17:08:31.346281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.088153ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T17:08:31.346472Z","caller":"traceutil/trace.go:171","msg":"trace[523978636] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"221.956434ms","start":"2026-04-08T17:08:31.124491Z","end":"2026-04-08T17:08:31.346447Z","steps":["trace[523978636] 'process raft request' (duration: 112.880137ms)","trace[523978636] 'compare' (duration: 107.890608ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T17:08:31.379335Z","caller":"traceutil/trace.go:171","msg":"trace[968178816] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"244.793505ms","start":"2026-04-08T17:08:31.134517Z","end":"2026-04-08T17:08:31.379311Z","steps":["trace[968178816] 'process raft request' (duration: 244.585891ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:08:43.241855Z","caller":"traceutil/trace.go:171","msg":"trace[1371907546] linearizableReadLoop","detail":"{readStateIndex:2034; appliedIndex:2033; }","duration":"128.897504ms","start":"2026-04-08T17:08:43.112938Z","end":"2026-04-08T17:08:43.241835Z","steps":["trace[1371907546] 'read index received' (duration: 128.768281ms)","trace[1371907546] 'applied index is now lower than readState.Index' (duration: 128.423µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T17:08:43.241922Z","caller":"traceutil/trace.go:171","msg":"trace[342541004] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"134.215751ms","start":"2026-04-08T17:08:43.107672Z","end":"2026-04-08T17:08:43.241888Z","steps":["trace[342541004] 'process raft request' (duration: 134.031007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:08:43.242111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.755982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-08T17:08:43.242142Z","caller":"traceutil/trace.go:171","msg":"trace[2093405543] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1960; }","duration":"126.820283ms","start":"2026-04-08T17:08:43.11531Z","end":"2026-04-08T17:08:43.242131Z","steps":["trace[2093405543] 'agreement among raft nodes before linearized reading' (duration: 126.69524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:08:43.242133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.15476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T17:08:43.242177Z","caller":"traceutil/trace.go:171","msg":"trace[1192070018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1960; }","duration":"129.244472ms","start":"2026-04-08T17:08:43.112921Z","end":"2026-04-08T17:08:43.242166Z","steps":["trace[1192070018] 'agreement among raft nodes before linearized reading' (duration: 129.046897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:08:43.242259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.93432ms","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-04-08T17:08:43.242336Z","caller":"traceutil/trace.go:171","msg":"trace[2140062115] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1960; }","duration":"123.043653ms","start":"2026-04-08T17:08:43.119273Z","end":"2026-04-08T17:08:43.242316Z","steps":["trace[2140062115] 'agreement among raft nodes before linearized reading' (duration: 122.812797ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:09:20.625584Z","caller":"traceutil/trace.go:171","msg":"trace[258749160] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"111.27723ms","start":"2026-04-08T17:09:20.51428Z","end":"2026-04-08T17:09:20.625557Z","steps":["trace[258749160] 'process raft request' (duration: 87.037046ms)","trace[258749160] 'compare' (duration: 24.08444ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T17:09:21.559177Z","caller":"traceutil/trace.go:171","msg":"trace[1423338309] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"128.027084ms","start":"2026-04-08T17:09:21.431129Z","end":"2026-04-08T17:09:21.559156Z","steps":["trace[1423338309] 'process raft request' (duration: 127.904111ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:09:21.673422Z","caller":"traceutil/trace.go:171","msg":"trace[1730431530] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"240.453111ms","start":"2026-04-08T17:09:21.432949Z","end":"2026-04-08T17:09:21.673402Z","steps":["trace[1730431530] 'process raft request' (duration: 240.169354ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:09:21.673536Z","caller":"traceutil/trace.go:171","msg":"trace[2126663577] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"141.58261ms","start":"2026-04-08T17:09:21.531948Z","end":"2026-04-08T17:09:21.673531Z","steps":["trace[2126663577] 'process raft request' (duration: 141.440016ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:09:21.673523Z","caller":"traceutil/trace.go:171","msg":"trace[940003144] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"239.790815ms","start":"2026-04-08T17:09:21.433694Z","end":"2026-04-08T17:09:21.673484Z","steps":["trace[940003144] 'process raft request' (duration: 239.631251ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:10:46.270537Z","caller":"traceutil/trace.go:171","msg":"trace[1007309601] linearizableReadLoop","detail":"{readStateIndex:2939; appliedIndex:2938; }","duration":"193.552755ms","start":"2026-04-08T17:10:46.076949Z","end":"2026-04-08T17:10:46.270502Z","steps":["trace[1007309601] 'read index received' (duration: 193.289588ms)","trace[1007309601] 'applied index is now lower than readState.Index' (duration: 261.817µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T17:10:46.270647Z","caller":"traceutil/trace.go:171","msg":"trace[778291751] transaction","detail":"{read_only:false; response_revision:2831; number_of_response:1; }","duration":"297.83428ms","start":"2026-04-08T17:10:45.972783Z","end":"2026-04-08T17:10:46.270617Z","steps":["trace[778291751] 'process raft request' (duration: 297.529443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:10:46.270854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.080562ms","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-04-08T17:10:46.27085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.826761ms","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-04-08T17:10:46.270898Z","caller":"traceutil/trace.go:171","msg":"trace[2135637837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2831; }","duration":"193.168975ms","start":"2026-04-08T17:10:46.077715Z","end":"2026-04-08T17:10:46.270884Z","steps":["trace[2135637837] 'agreement among raft nodes before linearized reading' (duration: 193.005281ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:10:46.270926Z","caller":"traceutil/trace.go:171","msg":"trace[1773675955] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2831; }","duration":"193.990745ms","start":"2026-04-08T17:10:46.076904Z","end":"2026-04-08T17:10:46.270895Z","steps":["trace[1773675955] 'agreement among raft nodes before linearized reading' (duration: 193.76551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:10:46.270935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.129984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T17:10:46.271002Z","caller":"traceutil/trace.go:171","msg":"trace[445226140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2831; }","duration":"193.232067ms","start":"2026-04-08T17:10:46.077752Z","end":"2026-04-08T17:10:46.270984Z","steps":["trace[445226140] 'agreement among raft nodes before linearized reading' (duration: 193.070212ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:13:11.169234Z","caller":"traceutil/trace.go:171","msg":"trace[1008582392] linearizableReadLoop","detail":"{readStateIndex:3939; appliedIndex:3938; }","duration":"243.923656ms","start":"2026-04-08T17:13:10.925276Z","end":"2026-04-08T17:13:11.1692Z","steps":["trace[1008582392] 'read index received' (duration: 243.725172ms)","trace[1008582392] 'applied index is now lower than readState.Index' (duration: 197.084µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T17:13:11.169356Z","caller":"traceutil/trace.go:171","msg":"trace[1567908389] transaction","detail":"{read_only:false; response_revision:3796; number_of_response:1; }","duration":"248.714522ms","start":"2026-04-08T17:13:10.920607Z","end":"2026-04-08T17:13:11.169321Z","steps":["trace[1567908389] 'process raft request' (duration: 248.414204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:13:11.169788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.506211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-08T17:13:11.169899Z","caller":"traceutil/trace.go:171","msg":"trace[2128065625] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3796; }","duration":"244.661685ms","start":"2026-04-08T17:13:10.925211Z","end":"2026-04-08T17:13:11.169872Z","steps":["trace[2128065625] 'agreement among raft nodes before linearized reading' (duration: 244.235105ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:13:11.171179Z","caller":"traceutil/trace.go:171","msg":"trace[709144248] transaction","detail":"{read_only:false; response_revision:3798; number_of_response:1; }","duration":"103.266794ms","start":"2026-04-08T17:13:11.067897Z","end":"2026-04-08T17:13:11.171164Z","steps":["trace[709144248] 'process raft request' (duration: 103.202642ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:13:11.171233Z","caller":"traceutil/trace.go:171","msg":"trace[1618468244] transaction","detail":"{read_only:false; response_revision:3797; number_of_response:1; }","duration":"153.02097ms","start":"2026-04-08T17:13:11.018179Z","end":"2026-04-08T17:13:11.1712Z","steps":["trace[1618468244] 'process raft request' (duration: 152.814475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:13:11.171816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.123255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-08T17:13:11.171879Z","caller":"traceutil/trace.go:171","msg":"trace[86865587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3799; }","duration":"245.228377ms","start":"2026-04-08T17:13:10.926636Z","end":"2026-04-08T17:13:11.171864Z","steps":["trace[86865587] 'agreement among raft nodes before linearized reading' (duration: 245.026313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:13:11.171886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.74241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"warn","ts":"2026-04-08T17:13:11.171826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.14599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tc6hd\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-08T17:13:11.172022Z","caller":"traceutil/trace.go:171","msg":"trace[782882533] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tc6hd; range_end:; response_count:1; response_revision:3799; }","duration":"241.359785ms","start":"2026-04-08T17:13:10.930648Z","end":"2026-04-08T17:13:11.172008Z","steps":["trace[782882533] 'agreement among raft nodes before linearized reading' (duration: 241.101099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:13:11.172616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.675269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-08T17:13:11.172729Z","caller":"traceutil/trace.go:171","msg":"trace[1591059775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3799; }","duration":"245.812513ms","start":"2026-04-08T17:13:10.926902Z","end":"2026-04-08T17:13:11.172714Z","steps":["trace[1591059775] 'agreement among raft nodes before linearized reading' (duration: 245.086504ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:13:11.17194Z","caller":"traceutil/trace.go:171","msg":"trace[851177875] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:3799; }","duration":"129.825062ms","start":"2026-04-08T17:13:11.042098Z","end":"2026-04-08T17:13:11.171923Z","steps":["trace[851177875] 'agreement among raft nodes before linearized reading' (duration: 129.575626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:14:43.646901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.354278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T17:14:43.647191Z","caller":"traceutil/trace.go:171","msg":"trace[927154771] transaction","detail":"{read_only:false; response_revision:5177; number_of_response:1; }","duration":"170.307955ms","start":"2026-04-08T17:14:43.476841Z","end":"2026-04-08T17:14:43.647149Z","steps":["trace[927154771] 'process raft request' (duration: 11.611929ms)","trace[927154771] 'compare' (duration: 158.227755ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T17:14:43.647321Z","caller":"traceutil/trace.go:171","msg":"trace[561987372] transaction","detail":"{read_only:false; response_revision:5178; number_of_response:1; }","duration":"167.329773ms","start":"2026-04-08T17:14:43.479973Z","end":"2026-04-08T17:14:43.647303Z","steps":["trace[561987372] 'process raft request' (duration: 167.053336ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:14:44.919174Z","caller":"traceutil/trace.go:171","msg":"trace[1018391280] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"111.688395ms","start":"2026-04-08T17:14:44.807444Z","end":"2026-04-08T17:14:44.919133Z","steps":["trace[1018391280] 'process raft request' (duration: 90.905646ms)","trace[1018391280] 'compare' (duration: 20.493762ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T17:14:50.54595Z","caller":"traceutil/trace.go:171","msg":"trace[2047091211] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"101.640629ms","start":"2026-04-08T17:14:50.444287Z","end":"2026-04-08T17:14:50.545928Z","steps":["trace[2047091211] 'process raft request' (duration: 101.340372ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:14:51.801393Z","caller":"traceutil/trace.go:171","msg":"trace[1392695694] transaction","detail":"{read_only:false; response_revision:5315; number_of_response:1; }","duration":"127.992718ms","start":"2026-04-08T17:14:51.673372Z","end":"2026-04-08T17:14:51.801365Z","steps":["trace[1392695694] 'process raft request' (duration: 127.783383ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:14:51.925642Z","caller":"traceutil/trace.go:171","msg":"trace[1925292882] linearizableReadLoop","detail":"{readStateIndex:5514; appliedIndex:5512; }","duration":"201.313729ms","start":"2026-04-08T17:14:51.7243Z","end":"2026-04-08T17:14:51.925614Z","steps":["trace[1925292882] 'read index received' (duration: 76.846787ms)","trace[1925292882] 'applied index is now lower than readState.Index' (duration: 124.465442ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T17:14:51.925696Z","caller":"traceutil/trace.go:171","msg":"trace[243701705] transaction","detail":"{read_only:false; response_revision:5316; number_of_response:1; }","duration":"242.894391ms","start":"2026-04-08T17:14:51.682754Z","end":"2026-04-08T17:14:51.925648Z","steps":["trace[243701705] 'process raft request' (duration: 152.895382ms)","trace[243701705] 'compare' (duration: 89.831815ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T17:14:51.925915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.594316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-08T17:14:51.925958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.746651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tc6hd\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-08T17:14:51.925995Z","caller":"traceutil/trace.go:171","msg":"trace[356526760] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5316; }","duration":"201.688518ms","start":"2026-04-08T17:14:51.724289Z","end":"2026-04-08T17:14:51.925977Z","steps":["trace[356526760] 'agreement among raft nodes before linearized reading' (duration: 201.448992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:14:51.926039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.125092ms","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-04-08T17:14:51.926043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.70943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-39a8413e-405f-483d-85dd-4386ac1197de\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-04-08T17:14:51.926105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.306893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-08T17:14:51.926006Z","caller":"traceutil/trace.go:171","msg":"trace[1347328579] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tc6hd; range_end:; response_count:1; response_revision:5316; }","duration":"199.842083ms","start":"2026-04-08T17:14:51.726148Z","end":"2026-04-08T17:14:51.92599Z","steps":["trace[1347328579] 'agreement among raft nodes before linearized reading' (duration: 199.654469ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:14:51.926137Z","caller":"traceutil/trace.go:171","msg":"trace[138228123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5316; }","duration":"192.351165ms","start":"2026-04-08T17:14:51.733777Z","end":"2026-04-08T17:14:51.926128Z","steps":["trace[138228123] 'agreement among raft nodes before linearized reading' (duration: 192.247432ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:14:51.926127Z","caller":"traceutil/trace.go:171","msg":"trace[482418545] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5316; }","duration":"190.243995ms","start":"2026-04-08T17:14:51.735861Z","end":"2026-04-08T17:14:51.926105Z","steps":["trace[482418545] 'agreement among raft nodes before linearized reading' (duration: 190.02198ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:14:51.926134Z","caller":"traceutil/trace.go:171","msg":"trace[1928008144] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-39a8413e-405f-483d-85dd-4386ac1197de; range_end:; response_count:1; response_revision:5316; }","duration":"163.843663ms","start":"2026-04-08T17:14:51.762271Z","end":"2026-04-08T17:14:51.926114Z","steps":["trace[1928008144] 'agreement among raft nodes before linearized reading' (duration: 163.597507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:14:51.926222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.605734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-08T17:14:51.926295Z","caller":"traceutil/trace.go:171","msg":"trace[693472875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5316; }","duration":"188.694907ms","start":"2026-04-08T17:14:51.737584Z","end":"2026-04-08T17:14:51.926279Z","steps":["trace[693472875] 'agreement among raft nodes before linearized reading' (duration: 188.532863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:14:51.926276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.043199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-08T17:14:51.926352Z","caller":"traceutil/trace.go:171","msg":"trace[548343464] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5316; }","duration":"195.1318ms","start":"2026-04-08T17:14:51.731199Z","end":"2026-04-08T17:14:51.926331Z","steps":["trace[548343464] 'agreement among raft nodes before linearized reading' (duration: 194.965716ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:14:52.058421Z","caller":"traceutil/trace.go:171","msg":"trace[244352285] transaction","detail":"{read_only:false; response_revision:5317; number_of_response:1; }","duration":"127.437145ms","start":"2026-04-08T17:14:51.930952Z","end":"2026-04-08T17:14:52.058389Z","steps":["trace[244352285] 'process raft request' (duration: 98.39745ms)","trace[244352285] 'compare' (duration: 28.890061ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T17:14:52.259235Z","caller":"traceutil/trace.go:171","msg":"trace[1655616695] transaction","detail":"{read_only:false; response_revision:5320; number_of_response:1; }","duration":"327.420701ms","start":"2026-04-08T17:14:51.931789Z","end":"2026-04-08T17:14:52.259209Z","steps":["trace[1655616695] 'process raft request' (duration: 327.279908ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:14:52.259274Z","caller":"traceutil/trace.go:171","msg":"trace[546859318] linearizableReadLoop","detail":"{readStateIndex:5520; appliedIndex:5514; }","duration":"281.77785ms","start":"2026-04-08T17:14:51.977474Z","end":"2026-04-08T17:14:52.259252Z","steps":["trace[546859318] 'read index received' (duration: 51.884308ms)","trace[546859318] 'applied index is now lower than readState.Index' (duration: 229.892492ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T17:14:52.259322Z","caller":"traceutil/trace.go:171","msg":"trace[1628646887] transaction","detail":"{read_only:false; response_revision:5318; number_of_response:1; }","duration":"328.320763ms","start":"2026-04-08T17:14:51.930987Z","end":"2026-04-08T17:14:52.259308Z","steps":["trace[1628646887] 'process raft request' (duration: 230.418614ms)","trace[1628646887] 'compare' (duration: 97.464648ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T17:14:52.259451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T17:14:51.931766Z","time spent":"327.548435ms","remote":"127.0.0.1:41556","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-04-08T17:14:52.259493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T17:14:51.930968Z","time spent":"328.392914ms","remote":"127.0.0.1:41556","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-04-08T17:14:52.259245Z","caller":"traceutil/trace.go:171","msg":"trace[669436392] transaction","detail":"{read_only:false; response_revision:5319; number_of_response:1; }","duration":"328.182509ms","start":"2026-04-08T17:14:51.931024Z","end":"2026-04-08T17:14:52.259207Z","steps":["trace[669436392] 'process raft request' (duration: 327.998735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:14:52.259662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T17:14:51.930993Z","time spent":"328.64408ms","remote":"127.0.0.1:41556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T17:14:52.25968Z","caller":"traceutil/trace.go:171","msg":"trace[1907157395] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"326.402516ms","start":"2026-04-08T17:14:51.933263Z","end":"2026-04-08T17:14:52.259666Z","steps":["trace[1907157395] 'process raft request' (duration: 325.888484ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:14:52.259655Z","caller":"traceutil/trace.go:171","msg":"trace[646957328] transaction","detail":"{read_only:false; response_revision:5321; number_of_response:1; }","duration":"327.284948ms","start":"2026-04-08T17:14:51.93232Z","end":"2026-04-08T17:14:52.259605Z","steps":["trace[646957328] 'process raft request' (duration: 326.787176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:14:52.259782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T17:14:51.933247Z","time spent":"326.461488ms","remote":"127.0.0.1:41480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3748,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T17:14:52.259818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T17:14:51.932304Z","time spent":"327.42536ms","remote":"127.0.0.1:41556","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-04-08T17:14:52.259862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.391196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-08T17:14:52.259904Z","caller":"traceutil/trace.go:171","msg":"trace[1601128580] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5322; }","duration":"282.478918ms","start":"2026-04-08T17:14:51.977413Z","end":"2026-04-08T17:14:52.259892Z","steps":["trace[1601128580] 'agreement among raft nodes before linearized reading' (duration: 282.348495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:14:52.260267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.35924ms","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-04-08T17:14:52.260315Z","caller":"traceutil/trace.go:171","msg":"trace[687936194] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:5322; }","duration":"134.449722ms","start":"2026-04-08T17:14:52.125854Z","end":"2026-04-08T17:14:52.260304Z","steps":["trace[687936194] 'agreement among raft nodes before linearized reading' (duration: 134.139555ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:14:54.082243Z","caller":"traceutil/trace.go:171","msg":"trace[2058002579] transaction","detail":"{read_only:false; response_revision:5347; number_of_response:1; }","duration":"149.604384ms","start":"2026-04-08T17:14:53.932625Z","end":"2026-04-08T17:14:54.082229Z","steps":["trace[2058002579] 'process raft request' (duration: 146.471759ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:14:54.082306Z","caller":"traceutil/trace.go:171","msg":"trace[2009122719] linearizableReadLoop","detail":"{readStateIndex:5546; appliedIndex:5545; }","duration":"146.677123ms","start":"2026-04-08T17:14:53.935615Z","end":"2026-04-08T17:14:54.082292Z","steps":["trace[2009122719] 'read index received' (duration: 143.486507ms)","trace[2009122719] 'applied index is now lower than readState.Index' (duration: 3.187266ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T17:14:54.082446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.818106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-39a8413e-405f-483d-85dd-4386ac1197de\" ","response":"range_response_count:1 size:3949"} {"level":"info","ts":"2026-04-08T17:14:54.082471Z","caller":"traceutil/trace.go:171","msg":"trace[1547754855] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-39a8413e-405f-483d-85dd-4386ac1197de; range_end:; response_count:1; response_revision:5348; }","duration":"146.878548ms","start":"2026-04-08T17:14:53.935586Z","end":"2026-04-08T17:14:54.082465Z","steps":["trace[1547754855] 'agreement among raft nodes before linearized reading' (duration: 146.768736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:14:54.082469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.195752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tc6hd\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-08T17:14:54.082495Z","caller":"traceutil/trace.go:171","msg":"trace[98631599] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tc6hd; range_end:; response_count:1; response_revision:5348; }","duration":"146.244703ms","start":"2026-04-08T17:14:53.936243Z","end":"2026-04-08T17:14:54.082487Z","steps":["trace[98631599] 'agreement among raft nodes before linearized reading' (duration: 146.1215ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:16:17.439203Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3004} {"level":"info","ts":"2026-04-08T17:16:17.526566Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3004,"took":"85.788253ms","hash":923847040,"current-db-size-bytes":57511936,"current-db-size":"58 MB","current-db-size-in-use-bytes":31502336,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-08T17:16:17.526634Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":923847040,"revision":3004,"compact-revision":-1} {"level":"info","ts":"2026-04-08T17:21:00.619379Z","caller":"traceutil/trace.go:171","msg":"trace[60507271] transaction","detail":"{read_only:false; response_revision:8204; number_of_response:1; }","duration":"309.798214ms","start":"2026-04-08T17:21:00.309553Z","end":"2026-04-08T17:21:00.619352Z","steps":["trace[60507271] 'process raft request' (duration: 309.615519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:21:00.619538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T17:21:00.309468Z","time spent":"309.984748ms","remote":"127.0.0.1:41556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T17:21:00.619702Z","caller":"traceutil/trace.go:171","msg":"trace[1475098439] linearizableReadLoop","detail":"{readStateIndex:8494; appliedIndex:8494; }","duration":"157.458543ms","start":"2026-04-08T17:21:00.462229Z","end":"2026-04-08T17:21:00.619688Z","steps":["trace[1475098439] 'read index received' (duration: 156.848948ms)","trace[1475098439] 'applied index is now lower than readState.Index' (duration: 608.065µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T17:21:00.619812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.583315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T17:21:00.619847Z","caller":"traceutil/trace.go:171","msg":"trace[2008827709] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:8204; }","duration":"157.652737ms","start":"2026-04-08T17:21:00.462185Z","end":"2026-04-08T17:21:00.619838Z","steps":["trace[2008827709] 'agreement among raft nodes before linearized reading' (duration: 157.560755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:21:00.620205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.021674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.168\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-08T17:21:00.620233Z","caller":"traceutil/trace.go:171","msg":"trace[1431573350] range","detail":"{range_begin:/registry/masterleases/199.19.213.168; range_end:; response_count:1; response_revision:8204; }","duration":"113.083194ms","start":"2026-04-08T17:21:00.507141Z","end":"2026-04-08T17:21:00.620224Z","steps":["trace[1431573350] 'agreement among raft nodes before linearized reading' (duration: 112.960801ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:21:12.018122Z","caller":"traceutil/trace.go:171","msg":"trace[615121747] transaction","detail":"{read_only:false; response_revision:8264; number_of_response:1; }","duration":"130.983522ms","start":"2026-04-08T17:21:11.887088Z","end":"2026-04-08T17:21:12.018071Z","steps":["trace[615121747] 'process raft request' (duration: 110.111474ms)","trace[615121747] 'compare' (duration: 20.371826ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T17:21:12.301208Z","caller":"traceutil/trace.go:171","msg":"trace[333145167] transaction","detail":"{read_only:false; response_revision:8267; number_of_response:1; }","duration":"104.199063ms","start":"2026-04-08T17:21:12.196965Z","end":"2026-04-08T17:21:12.301164Z","steps":["trace[333145167] 'process raft request' (duration: 70.021588ms)","trace[333145167] 'compare' (duration: 34.002641ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T17:21:12.330642Z","caller":"traceutil/trace.go:171","msg":"trace[1149603362] transaction","detail":"{read_only:false; response_revision:8268; number_of_response:1; }","duration":"132.830395ms","start":"2026-04-08T17:21:12.197777Z","end":"2026-04-08T17:21:12.330607Z","steps":["trace[1149603362] 'process raft request' (duration: 132.582749ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:21:12.33085Z","caller":"traceutil/trace.go:171","msg":"trace[445626651] transaction","detail":"{read_only:false; response_revision:8269; number_of_response:1; }","duration":"132.930117ms","start":"2026-04-08T17:21:12.197849Z","end":"2026-04-08T17:21:12.330779Z","steps":["trace[445626651] 'process raft request' (duration: 132.686911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:21:12.717486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.445565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-08T17:21:12.717585Z","caller":"traceutil/trace.go:171","msg":"trace[274128647] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:8269; }","duration":"231.6137ms","start":"2026-04-08T17:21:12.485951Z","end":"2026-04-08T17:21:12.717565Z","steps":["trace[274128647] 'count revisions from in-memory index tree' (duration: 231.339583ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:21:17.445708Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6101} {"level":"info","ts":"2026-04-08T17:21:17.541106Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6101,"took":"93.496637ms","hash":2151955965,"current-db-size-bytes":57511936,"current-db-size":"58 MB","current-db-size-in-use-bytes":23691264,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-08T17:21:17.541178Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2151955965,"revision":6101,"compact-revision":3004} {"level":"info","ts":"2026-04-08T17:21:41.673953Z","caller":"traceutil/trace.go:171","msg":"trace[861054659] transaction","detail":"{read_only:false; response_revision:8477; number_of_response:1; }","duration":"141.717698ms","start":"2026-04-08T17:21:41.532208Z","end":"2026-04-08T17:21:41.673926Z","steps":["trace[861054659] 'process raft request' (duration: 141.515403ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:21:43.83785Z","caller":"traceutil/trace.go:171","msg":"trace[566972004] transaction","detail":"{read_only:false; response_revision:8488; number_of_response:1; }","duration":"158.247711ms","start":"2026-04-08T17:21:43.679575Z","end":"2026-04-08T17:21:43.837823Z","steps":["trace[566972004] 'process raft request' (duration: 158.011305ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:21:54.069745Z","caller":"traceutil/trace.go:171","msg":"trace[278087159] transaction","detail":"{read_only:false; response_revision:8582; number_of_response:1; }","duration":"177.904531ms","start":"2026-04-08T17:21:53.891797Z","end":"2026-04-08T17:21:54.069702Z","steps":["trace[278087159] 'process raft request' (duration: 97.905464ms)","trace[278087159] 'compare' (duration: 79.805483ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T17:21:54.810663Z","caller":"traceutil/trace.go:171","msg":"trace[713703732] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"139.557615ms","start":"2026-04-08T17:21:54.671072Z","end":"2026-04-08T17:21:54.81063Z","steps":["trace[713703732] 'process raft request' (duration: 70.287195ms)","trace[713703732] 'compare' (duration: 69.059625ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T17:21:55.344322Z","caller":"traceutil/trace.go:171","msg":"trace[1217779344] transaction","detail":"{read_only:false; response_revision:8596; number_of_response:1; }","duration":"101.318134ms","start":"2026-04-08T17:21:55.242976Z","end":"2026-04-08T17:21:55.344295Z","steps":["trace[1217779344] 'process raft request' (duration: 97.552205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:21:56.138163Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12931696476066374392,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T17:21:56.341156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.075866ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-08T17:21:56.341571Z","caller":"traceutil/trace.go:171","msg":"trace[393228028] linearizableReadLoop","detail":"{readStateIndex:8906; appliedIndex:8904; }","duration":"704.386158ms","start":"2026-04-08T17:21:55.63716Z","end":"2026-04-08T17:21:56.341546Z","steps":["trace[393228028] 'read index received' (duration: 548.914562ms)","trace[393228028] 'applied index is now lower than readState.Index' (duration: 155.469946ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T17:21:56.3417Z","caller":"traceutil/trace.go:171","msg":"trace[1183002730] transaction","detail":"{read_only:false; response_revision:8598; number_of_response:1; }","duration":"467.241275ms","start":"2026-04-08T17:21:55.874447Z","end":"2026-04-08T17:21:56.341688Z","steps":["trace[1183002730] 'process raft request' (duration: 466.881347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:21:56.341837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T17:21:55.874416Z","time spent":"467.321677ms","remote":"127.0.0.1:41556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T17:21:56.342339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.520302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T17:21:56.342459Z","caller":"traceutil/trace.go:171","msg":"trace[1606705662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8598; }","duration":"506.697136ms","start":"2026-04-08T17:21:55.835723Z","end":"2026-04-08T17:21:56.342421Z","steps":["trace[1606705662] 'agreement among raft nodes before linearized reading' (duration: 506.478371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:21:56.342518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T17:21:55.835694Z","time spent":"506.81472ms","remote":"127.0.0.1:41284","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-04-08T17:21:56.342589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.093029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T17:21:56.342646Z","caller":"traceutil/trace.go:171","msg":"trace[1028970115] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:8598; }","duration":"174.205901ms","start":"2026-04-08T17:21:56.168424Z","end":"2026-04-08T17:21:56.34263Z","steps":["trace[1028970115] 'agreement among raft nodes before linearized reading' (duration: 174.035867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:21:56.342541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.31656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T17:21:56.342893Z","caller":"traceutil/trace.go:171","msg":"trace[1696021926] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:8598; }","duration":"705.715729ms","start":"2026-04-08T17:21:55.637126Z","end":"2026-04-08T17:21:56.342841Z","steps":["trace[1696021926] 'agreement among raft nodes before linearized reading' (duration: 705.261739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:21:56.343024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T17:21:55.637107Z","time spent":"705.870823ms","remote":"127.0.0.1:41870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"info","ts":"2026-04-08T17:22:10.249897Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002363.wal"} {"level":"info","ts":"2026-04-08T17:23:09.203496Z","caller":"traceutil/trace.go:171","msg":"trace[607075774] transaction","detail":"{read_only:false; response_revision:9322; number_of_response:1; }","duration":"266.691379ms","start":"2026-04-08T17:23:08.936779Z","end":"2026-04-08T17:23:09.203471Z","steps":["trace[607075774] 'process raft request' (duration: 233.65441ms)","trace[607075774] 'compare' (duration: 32.884375ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T17:23:09.20403Z","caller":"traceutil/trace.go:171","msg":"trace[407845556] transaction","detail":"{read_only:false; response_revision:9323; number_of_response:1; }","duration":"267.17549ms","start":"2026-04-08T17:23:08.936817Z","end":"2026-04-08T17:23:09.203992Z","steps":["trace[407845556] 'process raft request' (duration: 266.614797ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:23:09.204172Z","caller":"traceutil/trace.go:171","msg":"trace[179231915] linearizableReadLoop","detail":"{readStateIndex:9650; appliedIndex:9648; }","duration":"259.909416ms","start":"2026-04-08T17:23:08.944234Z","end":"2026-04-08T17:23:09.204143Z","steps":["trace[179231915] 'read index received' (duration: 226.230582ms)","trace[179231915] 'applied index is now lower than readState.Index' (duration: 33.675924ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T17:23:09.204404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.604207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-04-08T17:23:09.204406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.532141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-08T17:23:09.204406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.172833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tc6hd\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-08T17:23:09.204443Z","caller":"traceutil/trace.go:171","msg":"trace[811919402] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9324; }","duration":"150.680358ms","start":"2026-04-08T17:23:09.053749Z","end":"2026-04-08T17:23:09.20443Z","steps":["trace[811919402] 'agreement among raft nodes before linearized reading' (duration: 150.558545ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:23:09.204449Z","caller":"traceutil/trace.go:171","msg":"trace[1333921780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9324; }","duration":"216.582072ms","start":"2026-04-08T17:23:08.987856Z","end":"2026-04-08T17:23:09.204438Z","steps":["trace[1333921780] 'agreement among raft nodes before linearized reading' (duration: 216.444919ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:23:09.204467Z","caller":"traceutil/trace.go:171","msg":"trace[1890505145] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tc6hd; range_end:; response_count:1; response_revision:9324; }","duration":"260.278146ms","start":"2026-04-08T17:23:08.944173Z","end":"2026-04-08T17:23:09.204451Z","steps":["trace[1890505145] 'agreement among raft nodes before linearized reading' (duration: 260.0617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:23:09.204454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.25904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T17:23:09.204515Z","caller":"traceutil/trace.go:171","msg":"trace[949815703] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9324; }","duration":"199.379922ms","start":"2026-04-08T17:23:09.005117Z","end":"2026-04-08T17:23:09.204497Z","steps":["trace[949815703] 'agreement among raft nodes before linearized reading' (duration: 199.251849ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:23:09.357529Z","caller":"traceutil/trace.go:171","msg":"trace[1936676384] linearizableReadLoop","detail":"{readStateIndex:9653; appliedIndex:9651; }","duration":"148.203479ms","start":"2026-04-08T17:23:09.209299Z","end":"2026-04-08T17:23:09.357503Z","steps":["trace[1936676384] 'read index received' (duration: 6.866654ms)","trace[1936676384] 'applied index is now lower than readState.Index' (duration: 141.335765ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T17:23:09.357609Z","caller":"traceutil/trace.go:171","msg":"trace[1882581848] transaction","detail":"{read_only:false; response_revision:9326; number_of_response:1; }","duration":"148.459865ms","start":"2026-04-08T17:23:09.209106Z","end":"2026-04-08T17:23:09.357566Z","steps":["trace[1882581848] 'process raft request' (duration: 109.067375ms)","trace[1882581848] 'compare' (duration: 39.160065ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T17:23:09.357744Z","caller":"traceutil/trace.go:171","msg":"trace[1450395385] transaction","detail":"{read_only:false; response_revision:9327; number_of_response:1; }","duration":"148.354983ms","start":"2026-04-08T17:23:09.209346Z","end":"2026-04-08T17:23:09.357701Z","steps":["trace[1450395385] 'process raft request' (duration: 148.110127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T17:23:09.358253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.923666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:31 size:291261"} {"level":"info","ts":"2026-04-08T17:23:09.35831Z","caller":"traceutil/trace.go:171","msg":"trace[2085670294] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:31; response_revision:9327; }","duration":"149.035449ms","start":"2026-04-08T17:23:09.209261Z","end":"2026-04-08T17:23:09.358297Z","steps":["trace[2085670294] 'agreement among raft nodes before linearized reading' (duration: 148.349913ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T17:23:55.572443Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8ae391decac9b376","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-08T17:23:55.577118Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-08T17:23:55.577206Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-08T17:26:17.452408Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8299} {"level":"info","ts":"2026-04-08T17:26:17.531145Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8299,"took":"76.576729ms","hash":2489483407,"current-db-size-bytes":57511936,"current-db-size":"58 MB","current-db-size-in-use-bytes":24813568,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-08T17:26:17.531204Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2489483407,"revision":8299,"compact-revision":6101} {"level":"info","ts":"2026-04-08T17:31:17.45809Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10684} {"level":"info","ts":"2026-04-08T17:31:17.536132Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10684,"took":"76.172362ms","hash":4293107090,"current-db-size-bytes":57511936,"current-db-size":"58 MB","current-db-size-in-use-bytes":20434944,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-08T17:31:17.536191Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4293107090,"revision":10684,"compact-revision":8299}