{"level":"warn","ts":"2026-04-16T22:48:11.787212Z","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-16T22:48:11.787293Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70: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.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70: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-16T22:48:11.787369Z","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-16T22:48:11.787382Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-04-16T22:48:11.787399Z","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-16T22:48:11.788045Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-04-16T22:48:11.788137Z","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.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70: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-16T22:48:11.791396Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.054632ms"} {"level":"info","ts":"2026-04-16T22:48:11.805761Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-04-16T22:48:11.805895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T22:48:11.805918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-04-16T22:48:11.805932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T22:48:11.805942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-04-16T22:48:11.80598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-04-16T22:48:11.812863Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T22:48:11.818537Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T22:48:11.819828Z","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-16T22:48:11.822466Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T22:48:11.82275Z","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-16T22:48:11.822779Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T22:48:11.822892Z","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-16T22:48:11.822913Z","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-16T22:48:11.823519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-04-16T22:48:11.823672Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-04-16T22:48:11.825441Z","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-16T22:48:11.825533Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-04-16T22:48:11.825558Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-04-16T22:48:11.825637Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T22:48:11.825679Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T22:48:12.007088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T22:48:12.007141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T22:48:12.007176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-04-16T22:48:12.007192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-04-16T22:48:12.007202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-04-16T22:48:12.007213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-04-16T22:48:12.007334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-04-16T22:48:12.008836Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T22:48:12.008841Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T22:48:12.008865Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:48:12.008888Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T22:48:12.009077Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T22:48:12.0091Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T22:48:12.009656Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:48:12.009803Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:48:12.009851Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:48:12.010697Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-04-16T22:48:12.012979Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-16T22:48:14.963428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.235588ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:48:14.963804Z","caller":"traceutil/trace.go:171","msg":"trace[1358107363] transaction","detail":"{read_only:false; response_revision:158; number_of_response:1; }","duration":"276.866026ms","start":"2026-04-16T22:48:14.686913Z","end":"2026-04-16T22:48:14.963779Z","steps":["trace[1358107363] 'process raft request' (duration: 120.618013ms)","trace[1358107363] 'compare' (duration: 155.044044ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:48:24.499774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.882589ms","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-16T22:48:24.499877Z","caller":"traceutil/trace.go:171","msg":"trace[1818488587] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:402; }","duration":"110.042733ms","start":"2026-04-16T22:48:24.38981Z","end":"2026-04-16T22:48:24.499853Z","steps":["trace[1818488587] 'range keys from in-memory index tree' (duration: 109.738776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:48:24.499811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.929087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T22:48:24.50006Z","caller":"traceutil/trace.go:171","msg":"trace[810496649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:402; }","duration":"179.090621ms","start":"2026-04-16T22:48:24.320843Z","end":"2026-04-16T22:48:24.499933Z","steps":["trace[810496649] 'range keys from in-memory index tree' (duration: 178.790224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:48:24.746663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.448818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:48:24.746741Z","caller":"traceutil/trace.go:171","msg":"trace[572977888] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"242.202759ms","start":"2026-04-16T22:48:24.504523Z","end":"2026-04-16T22:48:24.746725Z","steps":["trace[572977888] 'process raft request' (duration: 96.049294ms)","trace[572977888] 'compare' (duration: 145.339516ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:48:48.507393Z","caller":"traceutil/trace.go:171","msg":"trace[515685786] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"122.089209ms","start":"2026-04-16T22:48:48.385278Z","end":"2026-04-16T22:48:48.507367Z","steps":["trace[515685786] 'process raft request' (duration: 121.279311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:48:48.904084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.238278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:48:48.90418Z","caller":"traceutil/trace.go:171","msg":"trace[1543920320] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"243.247176ms","start":"2026-04-16T22:48:48.660917Z","end":"2026-04-16T22:48:48.904164Z","steps":["trace[1543920320] 'read index received' (duration: 110.921466ms)","trace[1543920320] 'applied index is now lower than readState.Index' (duration: 132.32461ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:48:48.904262Z","caller":"traceutil/trace.go:171","msg":"trace[1519918753] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"343.055939ms","start":"2026-04-16T22:48:48.561195Z","end":"2026-04-16T22:48:48.904251Z","steps":["trace[1519918753] 'process raft request' (duration: 210.557055ms)","trace[1519918753] 'compare' (duration: 132.105315ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:48:48.904331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.70992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:48:48.904378Z","caller":"traceutil/trace.go:171","msg":"trace[294116007] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:617; }","duration":"241.778272ms","start":"2026-04-16T22:48:48.662589Z","end":"2026-04-16T22:48:48.904368Z","steps":["trace[294116007] 'agreement among raft nodes before linearized reading' (duration: 241.690589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:48:48.904344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:48:48.56118Z","time spent":"343.134252ms","remote":"127.0.0.1:38506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:48:48.904561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.641296ms","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-16T22:48:48.904596Z","caller":"traceutil/trace.go:171","msg":"trace[299015098] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"243.696776ms","start":"2026-04-16T22:48:48.660887Z","end":"2026-04-16T22:48:48.904583Z","steps":["trace[299015098] 'agreement among raft nodes before linearized reading' (duration: 243.583824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:48:48.904562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.958259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:48:48.904674Z","caller":"traceutil/trace.go:171","msg":"trace[2131292553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"148.130303ms","start":"2026-04-16T22:48:48.756524Z","end":"2026-04-16T22:48:48.904654Z","steps":["trace[2131292553] 'agreement among raft nodes before linearized reading' (duration: 147.932908ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:48:49.694598Z","caller":"traceutil/trace.go:171","msg":"trace[992228380] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"317.332674ms","start":"2026-04-16T22:48:49.377228Z","end":"2026-04-16T22:48:49.694561Z","steps":["trace[992228380] 'read index received' (duration: 316.178206ms)","trace[992228380] 'applied index is now lower than readState.Index' (duration: 1.153308ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:48:49.69466Z","caller":"traceutil/trace.go:171","msg":"trace[200002821] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"384.657881ms","start":"2026-04-16T22:48:49.309964Z","end":"2026-04-16T22:48:49.694622Z","steps":["trace[200002821] 'process raft request' (duration: 383.79496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:48:49.694835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:48:49.309922Z","time spent":"384.824685ms","remote":"127.0.0.1:38414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:48:49.694865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.636741ms","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-04-16T22:48:49.694916Z","caller":"traceutil/trace.go:171","msg":"trace[574743847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:620; }","duration":"317.740133ms","start":"2026-04-16T22:48:49.377162Z","end":"2026-04-16T22:48:49.694902Z","steps":["trace[574743847] 'agreement among raft nodes before linearized reading' (duration: 317.543218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:48:49.694958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:48:49.377135Z","time spent":"317.815145ms","remote":"127.0.0.1:38586","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-04-16T22:49:06.817851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.16698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-c26xz\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-16T22:49:06.817941Z","caller":"traceutil/trace.go:171","msg":"trace[1043438948] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-c26xz; range_end:; response_count:1; response_revision:787; }","duration":"161.294433ms","start":"2026-04-16T22:49:06.65663Z","end":"2026-04-16T22:49:06.817925Z","steps":["trace[1043438948] 'range keys from in-memory index tree' (duration: 161.032357ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:50:16.622074Z","caller":"traceutil/trace.go:171","msg":"trace[1474188704] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"125.915298ms","start":"2026-04-16T22:50:16.496134Z","end":"2026-04-16T22:50:16.622049Z","steps":["trace[1474188704] 'process raft request' (duration: 125.728544ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:50:24.199836Z","caller":"traceutil/trace.go:171","msg":"trace[904014851] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"131.330857ms","start":"2026-04-16T22:50:24.068471Z","end":"2026-04-16T22:50:24.199801Z","steps":["trace[904014851] 'process raft request' (duration: 124.349191ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:50:24.200025Z","caller":"traceutil/trace.go:171","msg":"trace[1725842931] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"131.200214ms","start":"2026-04-16T22:50:24.068815Z","end":"2026-04-16T22:50:24.200015Z","steps":["trace[1725842931] 'process raft request' (duration: 131.107541ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:50:24.200193Z","caller":"traceutil/trace.go:171","msg":"trace[1087379140] linearizableReadLoop","detail":"{readStateIndex:1970; appliedIndex:1966; }","duration":"125.094079ms","start":"2026-04-16T22:50:24.07509Z","end":"2026-04-16T22:50:24.200184Z","steps":["trace[1087379140] 'read index received' (duration: 117.730815ms)","trace[1087379140] 'applied index is now lower than readState.Index' (duration: 7.362594ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:50:24.200223Z","caller":"traceutil/trace.go:171","msg":"trace[2027345350] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"131.375858ms","start":"2026-04-16T22:50:24.068794Z","end":"2026-04-16T22:50:24.20017Z","steps":["trace[2027345350] 'process raft request' (duration: 130.960318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:50:24.200322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.222122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-16T22:50:24.200354Z","caller":"traceutil/trace.go:171","msg":"trace[1665018476] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1900; }","duration":"125.289484ms","start":"2026-04-16T22:50:24.075055Z","end":"2026-04-16T22:50:24.200345Z","steps":["trace[1665018476] 'agreement among raft nodes before linearized reading' (duration: 125.161321ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:50:24.200312Z","caller":"traceutil/trace.go:171","msg":"trace[1526417830] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"130.949188ms","start":"2026-04-16T22:50:24.069327Z","end":"2026-04-16T22:50:24.200276Z","steps":["trace[1526417830] 'process raft request' (duration: 130.657901ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:01.905905Z","caller":"traceutil/trace.go:171","msg":"trace[896353111] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"164.911171ms","start":"2026-04-16T22:51:01.740963Z","end":"2026-04-16T22:51:01.905874Z","steps":["trace[896353111] 'process raft request' (duration: 124.831328ms)","trace[896353111] 'compare' (duration: 39.814517ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:51:01.905971Z","caller":"traceutil/trace.go:171","msg":"trace[1164153169] linearizableReadLoop","detail":"{readStateIndex:2204; appliedIndex:2203; }","duration":"148.846519ms","start":"2026-04-16T22:51:01.756873Z","end":"2026-04-16T22:51:01.905719Z","steps":["trace[1164153169] 'read index received' (duration: 8.396279ms)","trace[1164153169] 'applied index is now lower than readState.Index' (duration: 140.44924ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:51:01.906072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.188147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:51:01.906278Z","caller":"traceutil/trace.go:171","msg":"trace[1087801720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2126; }","duration":"149.433562ms","start":"2026-04-16T22:51:01.756822Z","end":"2026-04-16T22:51:01.906255Z","steps":["trace[1087801720] 'agreement among raft nodes before linearized reading' (duration: 149.181096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:01.906178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.250793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:51:01.906468Z","caller":"traceutil/trace.go:171","msg":"trace[645037267] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:2126; }","duration":"105.57278ms","start":"2026-04-16T22:51:01.800863Z","end":"2026-04-16T22:51:01.906436Z","steps":["trace[645037267] 'agreement among raft nodes before linearized reading' (duration: 105.272203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:02.587305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.408278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-16T22:51:02.587407Z","caller":"traceutil/trace.go:171","msg":"trace[401764686] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2127; }","duration":"193.582113ms","start":"2026-04-16T22:51:02.3938Z","end":"2026-04-16T22:51:02.587382Z","steps":["trace[401764686] 'range keys from in-memory index tree' (duration: 193.232685ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:02.623045Z","caller":"traceutil/trace.go:171","msg":"trace[1570801219] linearizableReadLoop","detail":"{readStateIndex:2206; appliedIndex:2205; }","duration":"177.125341ms","start":"2026-04-16T22:51:02.445899Z","end":"2026-04-16T22:51:02.623024Z","steps":["trace[1570801219] 'read index received' (duration: 176.983898ms)","trace[1570801219] 'applied index is now lower than readState.Index' (duration: 140.443µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:51:02.623269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.359396ms","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-04-16T22:51:02.623348Z","caller":"traceutil/trace.go:171","msg":"trace[1922475658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2128; }","duration":"177.49016ms","start":"2026-04-16T22:51:02.445839Z","end":"2026-04-16T22:51:02.623329Z","steps":["trace[1922475658] 'agreement among raft nodes before linearized reading' (duration: 177.268555ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:02.623711Z","caller":"traceutil/trace.go:171","msg":"trace[321489902] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"186.890724ms","start":"2026-04-16T22:51:02.436766Z","end":"2026-04-16T22:51:02.623656Z","steps":["trace[321489902] 'process raft request' (duration: 186.136767ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:02.909417Z","caller":"traceutil/trace.go:171","msg":"trace[1692063820] linearizableReadLoop","detail":"{readStateIndex:2207; appliedIndex:2206; }","duration":"286.283647ms","start":"2026-04-16T22:51:02.623106Z","end":"2026-04-16T22:51:02.90939Z","steps":["trace[1692063820] 'read index received' (duration: 262.274115ms)","trace[1692063820] 'applied index is now lower than readState.Index' (duration: 24.008532ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:51:02.909581Z","caller":"traceutil/trace.go:171","msg":"trace[1166472725] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"318.013141ms","start":"2026-04-16T22:51:02.591447Z","end":"2026-04-16T22:51:02.90946Z","steps":["trace[1166472725] 'process raft request' (duration: 293.916348ms)","trace[1166472725] 'compare' (duration: 23.855088ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:51:02.909735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.151925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T22:51:02.909776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:02.591427Z","time spent":"318.226797ms","remote":"127.0.0.1:38586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:51:02.909979Z","caller":"traceutil/trace.go:171","msg":"trace[838151797] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:2129; }","duration":"461.284968ms","start":"2026-04-16T22:51:02.448504Z","end":"2026-04-16T22:51:02.909789Z","steps":["trace[838151797] 'agreement among raft nodes before linearized reading' (duration: 461.015882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:02.910544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:02.448474Z","time spent":"462.052346ms","remote":"127.0.0.1:38606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-16T22:51:02.921402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.995686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-16T22:51:02.921407Z","caller":"traceutil/trace.go:171","msg":"trace[378306628] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"293.5712ms","start":"2026-04-16T22:51:02.627789Z","end":"2026-04-16T22:51:02.92136Z","steps":["trace[378306628] 'process raft request' (duration: 293.384356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:02.921477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.330416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:51:02.921478Z","caller":"traceutil/trace.go:171","msg":"trace[530254251] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2130; }","duration":"179.115788ms","start":"2026-04-16T22:51:02.742342Z","end":"2026-04-16T22:51:02.921458Z","steps":["trace[530254251] 'agreement among raft nodes before linearized reading' (duration: 178.933744ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:02.921529Z","caller":"traceutil/trace.go:171","msg":"trace[597420482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2130; }","duration":"164.399058ms","start":"2026-04-16T22:51:02.757103Z","end":"2026-04-16T22:51:02.921502Z","steps":["trace[597420482] 'agreement among raft nodes before linearized reading' (duration: 164.308206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:52.255251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.56031ms","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-16T22:51:52.255328Z","caller":"traceutil/trace.go:171","msg":"trace[1499841123] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2442; }","duration":"129.682703ms","start":"2026-04-16T22:51:52.125631Z","end":"2026-04-16T22:51:52.255313Z","steps":["trace[1499841123] 'range keys from in-memory index tree' (duration: 129.430867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:25.652273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.02406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-16T22:52:25.652378Z","caller":"traceutil/trace.go:171","msg":"trace[363937400] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:2626; }","duration":"193.186265ms","start":"2026-04-16T22:52:25.459168Z","end":"2026-04-16T22:52:25.652354Z","steps":["trace[363937400] 'range keys from in-memory index tree' (duration: 192.794215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:25.652504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.412369ms","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-16T22:52:25.652591Z","caller":"traceutil/trace.go:171","msg":"trace[1484149858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2626; }","duration":"298.533952ms","start":"2026-04-16T22:52:25.354027Z","end":"2026-04-16T22:52:25.652561Z","steps":["trace[1484149858] 'range keys from in-memory index tree' (duration: 298.163342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:25.652799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.003882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-16T22:52:25.652856Z","caller":"traceutil/trace.go:171","msg":"trace[1383245874] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2626; }","duration":"135.103574ms","start":"2026-04-16T22:52:25.517739Z","end":"2026-04-16T22:52:25.652842Z","steps":["trace[1383245874] 'range keys from in-memory index tree' (duration: 134.254332ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:00.279302Z","caller":"traceutil/trace.go:171","msg":"trace[1463939946] transaction","detail":"{read_only:false; response_revision:3631; number_of_response:1; }","duration":"252.41627ms","start":"2026-04-16T22:55:00.026853Z","end":"2026-04-16T22:55:00.27927Z","steps":["trace[1463939946] 'process raft request' (duration: 176.004118ms)","trace[1463939946] 'compare' (duration: 76.089224ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:55:00.279289Z","caller":"traceutil/trace.go:171","msg":"trace[944214186] linearizableReadLoop","detail":"{readStateIndex:3765; appliedIndex:3763; }","duration":"233.428977ms","start":"2026-04-16T22:55:00.045798Z","end":"2026-04-16T22:55:00.279227Z","steps":["trace[944214186] 'read index received' (duration: 19.908095ms)","trace[944214186] 'applied index is now lower than readState.Index' (duration: 213.519152ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:55:00.27948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.664582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-16T22:55:00.279545Z","caller":"traceutil/trace.go:171","msg":"trace[42890905] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3631; }","duration":"233.767705ms","start":"2026-04-16T22:55:00.045761Z","end":"2026-04-16T22:55:00.279528Z","steps":["trace[42890905] 'agreement among raft nodes before linearized reading' (duration: 233.56675ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:56:18.650901Z","caller":"traceutil/trace.go:171","msg":"trace[433919329] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"118.714409ms","start":"2026-04-16T22:56:18.53216Z","end":"2026-04-16T22:56:18.650874Z","steps":["trace[433919329] 'process raft request' (duration: 118.530744ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:56:37.412293Z","caller":"traceutil/trace.go:171","msg":"trace[279713682] linearizableReadLoop","detail":"{readStateIndex:5291; appliedIndex:5289; }","duration":"111.275882ms","start":"2026-04-16T22:56:37.300981Z","end":"2026-04-16T22:56:37.412257Z","steps":["trace[279713682] 'read index received' (duration: 47.647561ms)","trace[279713682] 'applied index is now lower than readState.Index' (duration: 63.627281ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:56:37.412379Z","caller":"traceutil/trace.go:171","msg":"trace[1232229492] transaction","detail":"{read_only:false; response_revision:5105; number_of_response:1; }","duration":"122.864378ms","start":"2026-04-16T22:56:37.289465Z","end":"2026-04-16T22:56:37.412329Z","steps":["trace[1232229492] 'process raft request' (duration: 122.627742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:56:37.412784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.750974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-16T22:56:37.413485Z","caller":"traceutil/trace.go:171","msg":"trace[1437518105] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5105; }","duration":"112.525992ms","start":"2026-04-16T22:56:37.300929Z","end":"2026-04-16T22:56:37.413455Z","steps":["trace[1437518105] 'agreement among raft nodes before linearized reading' (duration: 111.457927ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:56:37.544341Z","caller":"traceutil/trace.go:171","msg":"trace[1492069450] transaction","detail":"{read_only:false; response_revision:5106; number_of_response:1; }","duration":"126.209506ms","start":"2026-04-16T22:56:37.417405Z","end":"2026-04-16T22:56:37.543614Z","steps":["trace[1492069450] 'process raft request' (duration: 71.128779ms)","trace[1492069450] 'compare' (duration: 54.862292ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:56:37.73554Z","caller":"traceutil/trace.go:171","msg":"trace[1403389445] transaction","detail":"{read_only:false; response_revision:5107; number_of_response:1; }","duration":"176.44127ms","start":"2026-04-16T22:56:37.559066Z","end":"2026-04-16T22:56:37.735507Z","steps":["trace[1403389445] 'process raft request' (duration: 153.742651ms)","trace[1403389445] 'compare' (duration: 22.438923ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:58:12.218918Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2871} {"level":"info","ts":"2026-04-16T22:58:12.284482Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2871,"took":"64.124692ms","hash":3378537508,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":32923648,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-16T22:58:12.284541Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3378537508,"revision":2871,"compact-revision":-1} {"level":"warn","ts":"2026-04-16T23:02:46.964938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.708913ms","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-16T23:02:46.965052Z","caller":"traceutil/trace.go:171","msg":"trace[1882213092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7995; }","duration":"346.872527ms","start":"2026-04-16T23:02:46.61815Z","end":"2026-04-16T23:02:46.965022Z","steps":["trace[1882213092] 'range keys from in-memory index tree' (duration: 346.500508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:02:46.965034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.595698ms","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-16T23:02:46.965089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.973829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T23:02:46.965124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T23:02:46.618129Z","time spent":"346.98115ms","remote":"127.0.0.1:38586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-16T23:02:46.965142Z","caller":"traceutil/trace.go:171","msg":"trace[1965534190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7995; }","duration":"306.751892ms","start":"2026-04-16T23:02:46.658359Z","end":"2026-04-16T23:02:46.965111Z","steps":["trace[1965534190] 'range keys from in-memory index tree' (duration: 306.400654ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:02:46.965168Z","caller":"traceutil/trace.go:171","msg":"trace[1022217114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7995; }","duration":"268.129633ms","start":"2026-04-16T23:02:46.697019Z","end":"2026-04-16T23:02:46.965149Z","steps":["trace[1022217114] 'range keys from in-memory index tree' (duration: 267.850316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:02:46.965271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.837201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T23:02:46.965459Z","caller":"traceutil/trace.go:171","msg":"trace[672312475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7995; }","duration":"209.093107ms","start":"2026-04-16T23:02:46.756332Z","end":"2026-04-16T23:02:46.965425Z","steps":["trace[672312475] 'range keys from in-memory index tree' (duration: 208.670778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:02:46.965203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T23:02:46.658335Z","time spent":"306.856445ms","remote":"127.0.0.1:38586","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-04-16T23:02:47.25466Z","caller":"traceutil/trace.go:171","msg":"trace[65801950] transaction","detail":"{read_only:false; response_revision:7999; number_of_response:1; }","duration":"283.472707ms","start":"2026-04-16T23:02:46.971165Z","end":"2026-04-16T23:02:47.254638Z","steps":["trace[65801950] 'process raft request' (duration: 283.429486ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:02:47.254694Z","caller":"traceutil/trace.go:171","msg":"trace[1974948537] transaction","detail":"{read_only:false; response_revision:7997; number_of_response:1; }","duration":"284.157313ms","start":"2026-04-16T23:02:46.970503Z","end":"2026-04-16T23:02:47.25466Z","steps":["trace[1974948537] 'process raft request' (duration: 283.889407ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:02:47.254716Z","caller":"traceutil/trace.go:171","msg":"trace[715293708] transaction","detail":"{read_only:false; response_revision:7998; number_of_response:1; }","duration":"283.823686ms","start":"2026-04-16T23:02:46.970865Z","end":"2026-04-16T23:02:47.254688Z","steps":["trace[715293708] 'process raft request' (duration: 283.686862ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:02:47.41493Z","caller":"traceutil/trace.go:171","msg":"trace[171109377] linearizableReadLoop","detail":"{readStateIndex:8278; appliedIndex:8274; }","duration":"385.786264ms","start":"2026-04-16T23:02:47.02911Z","end":"2026-04-16T23:02:47.414896Z","steps":["trace[171109377] 'read index received' (duration: 225.336544ms)","trace[171109377] 'applied index is now lower than readState.Index' (duration: 160.44836ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T23:02:47.414988Z","caller":"traceutil/trace.go:171","msg":"trace[140475862] transaction","detail":"{read_only:false; response_revision:8000; number_of_response:1; }","duration":"390.903306ms","start":"2026-04-16T23:02:47.024038Z","end":"2026-04-16T23:02:47.414941Z","steps":["trace[140475862] 'process raft request' (duration: 321.708769ms)","trace[140475862] 'compare' (duration: 68.991711ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T23:02:47.415206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T23:02:47.02401Z","time spent":"391.070649ms","remote":"127.0.0.1:38586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T23:02:47.415262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.104861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2vzbq\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-16T23:02:47.415369Z","caller":"traceutil/trace.go:171","msg":"trace[1667527350] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2vzbq; range_end:; response_count:1; response_revision:8000; }","duration":"386.291625ms","start":"2026-04-16T23:02:47.029044Z","end":"2026-04-16T23:02:47.415336Z","steps":["trace[1667527350] 'agreement among raft nodes before linearized reading' (duration: 385.984448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:02:47.415443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T23:02:47.029022Z","time spent":"386.406438ms","remote":"127.0.0.1:38506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-2vzbq\" "} {"level":"warn","ts":"2026-04-16T23:02:47.415743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.621478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T23:02:47.415797Z","caller":"traceutil/trace.go:171","msg":"trace[310532524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8000; }","duration":"219.732541ms","start":"2026-04-16T23:02:47.196053Z","end":"2026-04-16T23:02:47.415785Z","steps":["trace[310532524] 'agreement among raft nodes before linearized reading' (duration: 219.010464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:02:47.415618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.306275ms","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-16T23:02:47.416254Z","caller":"traceutil/trace.go:171","msg":"trace[88211556] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8000; }","duration":"186.987741ms","start":"2026-04-16T23:02:47.229243Z","end":"2026-04-16T23:02:47.416231Z","steps":["trace[88211556] 'agreement among raft nodes before linearized reading' (duration: 185.856065ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:02:47.716381Z","caller":"traceutil/trace.go:171","msg":"trace[2137675011] transaction","detail":"{read_only:false; response_revision:8003; number_of_response:1; }","duration":"131.969381ms","start":"2026-04-16T23:02:47.584385Z","end":"2026-04-16T23:02:47.716355Z","steps":["trace[2137675011] 'process raft request' (duration: 131.692145ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:03:12.224307Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5962} {"level":"info","ts":"2026-04-16T23:03:12.306832Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5962,"took":"80.589849ms","hash":2844763768,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":26591232,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-16T23:03:12.306882Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2844763768,"revision":5962,"compact-revision":2871} {"level":"info","ts":"2026-04-16T23:03:26.968367Z","caller":"traceutil/trace.go:171","msg":"trace[1830604895] transaction","detail":"{read_only:false; response_revision:8304; number_of_response:1; }","duration":"130.665061ms","start":"2026-04-16T23:03:26.83768Z","end":"2026-04-16T23:03:26.968345Z","steps":["trace[1830604895] 'process raft request' (duration: 130.510107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:03:27.915605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.22495ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T23:03:27.915738Z","caller":"traceutil/trace.go:171","msg":"trace[270927004] linearizableReadLoop","detail":"{readStateIndex:8604; appliedIndex:8603; }","duration":"263.486652ms","start":"2026-04-16T23:03:27.652229Z","end":"2026-04-16T23:03:27.915716Z","steps":["trace[270927004] 'read index received' (duration: 6.975735ms)","trace[270927004] 'applied index is now lower than readState.Index' (duration: 256.508787ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T23:03:27.915805Z","caller":"traceutil/trace.go:171","msg":"trace[933999761] transaction","detail":"{read_only:false; response_revision:8312; number_of_response:1; }","duration":"284.241336ms","start":"2026-04-16T23:03:27.631531Z","end":"2026-04-16T23:03:27.915773Z","steps":["trace[933999761] 'process raft request' (duration: 27.7489ms)","trace[933999761] 'compare' (duration: 256.076406ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T23:03:27.916202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.966233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2vzbq\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-16T23:03:27.916286Z","caller":"traceutil/trace.go:171","msg":"trace[965203214] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2vzbq; range_end:; response_count:1; response_revision:8312; }","duration":"264.096126ms","start":"2026-04-16T23:03:27.652171Z","end":"2026-04-16T23:03:27.916267Z","steps":["trace[965203214] 'agreement among raft nodes before linearized reading' (duration: 263.605254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:03:28.359067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.891819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T23:03:28.359173Z","caller":"traceutil/trace.go:171","msg":"trace[1008107261] linearizableReadLoop","detail":"{readStateIndex:8605; appliedIndex:8604; }","duration":"443.374994ms","start":"2026-04-16T23:03:27.915781Z","end":"2026-04-16T23:03:28.359156Z","steps":["trace[1008107261] 'read index received' (duration: 235.193469ms)","trace[1008107261] 'applied index is now lower than readState.Index' (duration: 208.180275ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T23:03:28.35947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.259659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T23:03:28.359483Z","caller":"traceutil/trace.go:171","msg":"trace[1127166556] transaction","detail":"{read_only:false; response_revision:8313; number_of_response:1; }","duration":"555.439221ms","start":"2026-04-16T23:03:27.804012Z","end":"2026-04-16T23:03:28.359451Z","steps":["trace[1127166556] 'process raft request' (duration: 347.079831ms)","trace[1127166556] 'compare' (duration: 207.432668ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T23:03:28.359564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.278013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T23:03:28.359655Z","caller":"traceutil/trace.go:171","msg":"trace[398961105] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:8313; }","duration":"191.384565ms","start":"2026-04-16T23:03:28.168252Z","end":"2026-04-16T23:03:28.359637Z","steps":["trace[398961105] 'agreement among raft nodes before linearized reading' (duration: 191.224601ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:03:28.359579Z","caller":"traceutil/trace.go:171","msg":"trace[1760777485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8313; }","duration":"603.418523ms","start":"2026-04-16T23:03:27.756133Z","end":"2026-04-16T23:03:28.359551Z","steps":["trace[1760777485] 'agreement among raft nodes before linearized reading' (duration: 603.224469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:03:28.359749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.426903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-16T23:03:28.359752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.636046ms","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":"warn","ts":"2026-04-16T23:03:28.359822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T23:03:27.803984Z","time spent":"555.682737ms","remote":"127.0.0.1:38568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T23:03:28.359873Z","caller":"traceutil/trace.go:171","msg":"trace[972797552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8313; }","duration":"687.594898ms","start":"2026-04-16T23:03:27.672247Z","end":"2026-04-16T23:03:28.359842Z","steps":["trace[972797552] 'agreement among raft nodes before linearized reading' (duration: 687.237479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:03:28.359958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T23:03:27.672236Z","time spent":"687.70614ms","remote":"127.0.0.1:38586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T23:03:28.359794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T23:03:27.756106Z","time spent":"603.671509ms","remote":"127.0.0.1:38316","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-16T23:03:28.359736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.851115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:211656"} {"level":"info","ts":"2026-04-16T23:03:28.360172Z","caller":"traceutil/trace.go:171","msg":"trace[978680468] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8313; }","duration":"439.286946ms","start":"2026-04-16T23:03:27.920872Z","end":"2026-04-16T23:03:28.360159Z","steps":["trace[978680468] 'agreement among raft nodes before linearized reading' (duration: 438.571699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:03:28.360218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T23:03:27.92079Z","time spent":"439.410949ms","remote":"127.0.0.1:38506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":25,"response size":211679,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-04-16T23:03:28.359883Z","caller":"traceutil/trace.go:171","msg":"trace[1494729957] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8313; }","duration":"450.828221ms","start":"2026-04-16T23:03:27.90903Z","end":"2026-04-16T23:03:28.359858Z","steps":["trace[1494729957] 'agreement among raft nodes before linearized reading' (duration: 450.432321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:03:28.360456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T23:03:27.909006Z","time spent":"451.433446ms","remote":"127.0.0.1:38586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-16T23:03:29.428753Z","caller":"traceutil/trace.go:171","msg":"trace[540676401] linearizableReadLoop","detail":"{readStateIndex:8613; appliedIndex:8612; }","duration":"352.06479ms","start":"2026-04-16T23:03:29.076663Z","end":"2026-04-16T23:03:29.428728Z","steps":["trace[540676401] 'read index received' (duration: 351.808184ms)","trace[540676401] 'applied index is now lower than readState.Index' (duration: 255.366µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T23:03:29.428813Z","caller":"traceutil/trace.go:171","msg":"trace[1440975768] transaction","detail":"{read_only:false; response_revision:8321; number_of_response:1; }","duration":"369.457675ms","start":"2026-04-16T23:03:29.059319Z","end":"2026-04-16T23:03:29.428776Z","steps":["trace[1440975768] 'process raft request' (duration: 369.200908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:03:29.428993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T23:03:29.059305Z","time spent":"369.575677ms","remote":"127.0.0.1:33446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7438,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T23:03:29.429352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.668434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:414319"} {"level":"info","ts":"2026-04-16T23:03:29.429401Z","caller":"traceutil/trace.go:171","msg":"trace[149729383] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:8321; }","duration":"352.758037ms","start":"2026-04-16T23:03:29.076632Z","end":"2026-04-16T23:03:29.42939Z","steps":["trace[149729383] 'agreement among raft nodes before linearized reading' (duration: 352.229305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:03:29.429441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T23:03:29.076622Z","time spent":"352.809838ms","remote":"127.0.0.1:52728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":414342,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"warn","ts":"2026-04-16T23:03:29.429461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.916ms","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-04-16T23:03:29.429571Z","caller":"traceutil/trace.go:171","msg":"trace[525312785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8321; }","duration":"161.073984ms","start":"2026-04-16T23:03:29.268477Z","end":"2026-04-16T23:03:29.429551Z","steps":["trace[525312785] 'agreement among raft nodes before linearized reading' (duration: 160.703895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:03:29.659907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.611939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T23:03:29.660097Z","caller":"traceutil/trace.go:171","msg":"trace[787600193] transaction","detail":"{read_only:false; response_revision:8323; number_of_response:1; }","duration":"225.13461ms","start":"2026-04-16T23:03:29.434933Z","end":"2026-04-16T23:03:29.660067Z","steps":["trace[787600193] 'process raft request' (duration: 81.258774ms)","trace[787600193] 'compare' (duration: 143.350962ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T23:03:29.66021Z","caller":"traceutil/trace.go:171","msg":"trace[1723850977] linearizableReadLoop","detail":"{readStateIndex:8615; appliedIndex:8614; }","duration":"200.283528ms","start":"2026-04-16T23:03:29.459896Z","end":"2026-04-16T23:03:29.66018Z","steps":["trace[1723850977] 'read index received' (duration: 56.235879ms)","trace[1723850977] 'applied index is now lower than readState.Index' (duration: 144.044029ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T23:03:29.66045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.527394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T23:03:29.660494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.225796ms","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-04-16T23:03:29.660519Z","caller":"traceutil/trace.go:171","msg":"trace[242639578] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8324; }","duration":"200.640107ms","start":"2026-04-16T23:03:29.459855Z","end":"2026-04-16T23:03:29.660495Z","steps":["trace[242639578] 'agreement among raft nodes before linearized reading' (duration: 200.514324ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:03:29.660532Z","caller":"traceutil/trace.go:171","msg":"trace[1649756048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8324; }","duration":"192.292427ms","start":"2026-04-16T23:03:29.468228Z","end":"2026-04-16T23:03:29.66052Z","steps":["trace[1649756048] 'agreement among raft nodes before linearized reading' (duration: 192.155554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:03:29.660519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.952799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-04-16T23:03:29.660567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.00138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-16T23:03:29.660624Z","caller":"traceutil/trace.go:171","msg":"trace[731165762] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8324; }","duration":"176.071611ms","start":"2026-04-16T23:03:29.484521Z","end":"2026-04-16T23:03:29.660592Z","steps":["trace[731165762] 'agreement among raft nodes before linearized reading' (duration: 175.883506ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:03:29.660669Z","caller":"traceutil/trace.go:171","msg":"trace[1208233494] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8324; }","duration":"176.134223ms","start":"2026-04-16T23:03:29.48451Z","end":"2026-04-16T23:03:29.660645Z","steps":["trace[1208233494] 'agreement among raft nodes before linearized reading' (duration: 175.900328ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:03:29.94488Z","caller":"traceutil/trace.go:171","msg":"trace[1154234730] transaction","detail":"{read_only:false; response_revision:8328; number_of_response:1; }","duration":"114.991257ms","start":"2026-04-16T23:03:29.829867Z","end":"2026-04-16T23:03:29.944858Z","steps":["trace[1154234730] 'process raft request' (duration: 114.377503ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:03:30.594657Z","caller":"traceutil/trace.go:171","msg":"trace[2142393325] linearizableReadLoop","detail":"{readStateIndex:8623; appliedIndex:8622; }","duration":"166.589085ms","start":"2026-04-16T23:03:30.428046Z","end":"2026-04-16T23:03:30.594635Z","steps":["trace[2142393325] 'read index received' (duration: 166.475822ms)","trace[2142393325] 'applied index is now lower than readState.Index' (duration: 112.543µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T23:03:30.594685Z","caller":"traceutil/trace.go:171","msg":"trace[210151178] transaction","detail":"{read_only:false; response_revision:8331; number_of_response:1; }","duration":"213.202545ms","start":"2026-04-16T23:03:30.381466Z","end":"2026-04-16T23:03:30.594669Z","steps":["trace[210151178] 'process raft request' (duration: 213.038181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:03:30.594939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.902469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19316"} {"level":"info","ts":"2026-04-16T23:03:30.594983Z","caller":"traceutil/trace.go:171","msg":"trace[1155799856] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8331; }","duration":"153.014012ms","start":"2026-04-16T23:03:30.441956Z","end":"2026-04-16T23:03:30.59497Z","steps":["trace[1155799856] 'agreement among raft nodes before linearized reading' (duration: 152.809097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:03:30.595169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.120547ms","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-16T23:03:30.595204Z","caller":"traceutil/trace.go:171","msg":"trace[1981669378] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8331; }","duration":"167.194449ms","start":"2026-04-16T23:03:30.428003Z","end":"2026-04-16T23:03:30.595197Z","steps":["trace[1981669378] 'agreement among raft nodes before linearized reading' (duration: 166.714708ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:04:29.513373Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002407.wal"} {"level":"info","ts":"2026-04-16T23:06:02.400943Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f8844a9a4263041d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-16T23:06:02.405965Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-16T23:06:02.406032Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-16T23:08:12.230515Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8187} {"level":"info","ts":"2026-04-16T23:08:12.306197Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8187,"took":"73.09963ms","hash":2385250712,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":24780800,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-16T23:08:12.306243Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2385250712,"revision":8187,"compact-revision":5962} {"level":"info","ts":"2026-04-16T23:13:12.237066Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10566} {"level":"info","ts":"2026-04-16T23:13:12.3109Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10566,"took":"71.449839ms","hash":321202276,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":19918848,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-16T23:13:12.310977Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":321202276,"revision":10566,"compact-revision":8187}