{"level":"warn","ts":"2026-05-27T19:44:06.929691Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T19:44:06.92979Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.104: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.104:2380","--initial-cluster=instance=https://199.19.213.104:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.104:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.104: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-05-27T19:44:06.929861Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T19:44:06.929874Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.104:2380"]} {"level":"info","ts":"2026-05-27T19:44:06.929895Z","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-05-27T19:44:06.930593Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.104:2379"]} {"level":"info","ts":"2026-05-27T19:44:06.93067Z","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.104:2380"],"listen-peer-urls":["https://199.19.213.104:2380"],"advertise-client-urls":["https://199.19.213.104:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.104:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.104: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-05-27T19:44:06.935462Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.609939ms"} {"level":"info","ts":"2026-05-27T19:44:06.946667Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8a0c9a032e61a05a","cluster-id":"153e4a99345578f6"} {"level":"info","ts":"2026-05-27T19:44:06.946755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a switched to configuration voters=()"} {"level":"info","ts":"2026-05-27T19:44:06.946782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a became follower at term 0"} {"level":"info","ts":"2026-05-27T19:44:06.946796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8a0c9a032e61a05a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-27T19:44:06.946808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a became follower at term 1"} {"level":"info","ts":"2026-05-27T19:44:06.946846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a switched to configuration voters=(9947495015408312410)"} {"level":"warn","ts":"2026-05-27T19:44:06.95655Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-27T19:44:06.958445Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-27T19:44:06.959837Z","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-05-27T19:44:06.962708Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8a0c9a032e61a05a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-27T19:44:06.962943Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8a0c9a032e61a05a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-27T19:44:06.96309Z","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-05-27T19:44:06.963228Z","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-05-27T19:44:06.963268Z","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-05-27T19:44:06.963976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a switched to configuration voters=(9947495015408312410)"} {"level":"info","ts":"2026-05-27T19:44:06.964551Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"153e4a99345578f6","local-member-id":"8a0c9a032e61a05a","added-peer-id":"8a0c9a032e61a05a","added-peer-peer-urls":["https://199.19.213.104:2380"]} {"level":"info","ts":"2026-05-27T19:44:06.972032Z","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-05-27T19:44:06.972193Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.104:2380"} {"level":"info","ts":"2026-05-27T19:44:06.972226Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.104:2380"} {"level":"info","ts":"2026-05-27T19:44:06.972443Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8a0c9a032e61a05a","initial-advertise-peer-urls":["https://199.19.213.104:2380"],"listen-peer-urls":["https://199.19.213.104:2380"],"advertise-client-urls":["https://199.19.213.104:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.104:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-27T19:44:06.972513Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-27T19:44:07.9481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a is starting a new election at term 1"} {"level":"info","ts":"2026-05-27T19:44:07.948163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-27T19:44:07.948201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a received MsgPreVoteResp from 8a0c9a032e61a05a at term 1"} {"level":"info","ts":"2026-05-27T19:44:07.948303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a became candidate at term 2"} {"level":"info","ts":"2026-05-27T19:44:07.948327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a received MsgVoteResp from 8a0c9a032e61a05a at term 2"} {"level":"info","ts":"2026-05-27T19:44:07.948342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a became leader at term 2"} {"level":"info","ts":"2026-05-27T19:44:07.948356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8a0c9a032e61a05a elected leader 8a0c9a032e61a05a at term 2"} {"level":"info","ts":"2026-05-27T19:44:07.949281Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:44:07.950131Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8a0c9a032e61a05a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.104:2379]}","request-path":"/0/members/8a0c9a032e61a05a/attributes","cluster-id":"153e4a99345578f6","publish-timeout":"7s"} {"level":"info","ts":"2026-05-27T19:44:07.950277Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T19:44:07.950467Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T19:44:07.950593Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-27T19:44:07.950849Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"153e4a99345578f6","local-member-id":"8a0c9a032e61a05a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:44:07.950988Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:44:07.95105Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:44:07.950899Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-27T19:44:07.953939Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.104:2379"} {"level":"info","ts":"2026-05-27T19:44:07.954629Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-27T19:44:47.958115Z","caller":"traceutil/trace.go:171","msg":"trace[1417230377] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"140.344836ms","start":"2026-05-27T19:44:47.817733Z","end":"2026-05-27T19:44:47.958078Z","steps":["trace[1417230377] 'process raft request' (duration: 140.166651ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:48.352414Z","caller":"traceutil/trace.go:171","msg":"trace[935184959] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"218.110858ms","start":"2026-05-27T19:44:48.134271Z","end":"2026-05-27T19:44:48.352381Z","steps":["trace[935184959] 'process raft request' (duration: 217.446938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:48.803272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.627163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:44:48.803411Z","caller":"traceutil/trace.go:171","msg":"trace[715196735] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"310.526006ms","start":"2026-05-27T19:44:48.492868Z","end":"2026-05-27T19:44:48.803394Z","steps":["trace[715196735] 'read index received' (duration: 169.155521ms)","trace[715196735] 'applied index is now lower than readState.Index' (duration: 141.369345ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:44:48.80348Z","caller":"traceutil/trace.go:171","msg":"trace[1813838707] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"392.782626ms","start":"2026-05-27T19:44:48.410659Z","end":"2026-05-27T19:44:48.803442Z","steps":["trace[1813838707] 'process raft request' (duration: 251.471533ms)","trace[1813838707] 'compare' (duration: 140.297674ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:44:48.80357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.702031ms","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-05-27T19:44:48.803679Z","caller":"traceutil/trace.go:171","msg":"trace[1408162468] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"310.834485ms","start":"2026-05-27T19:44:48.492816Z","end":"2026-05-27T19:44:48.803651Z","steps":["trace[1408162468] 'agreement among raft nodes before linearized reading' (duration: 310.622649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:48.803657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:48.410632Z","time spent":"392.92177ms","remote":"127.0.0.1:48776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:44:48.803724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:48.492796Z","time spent":"310.919187ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T19:44:50.025635Z","caller":"traceutil/trace.go:171","msg":"trace[1057982689] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"108.590607ms","start":"2026-05-27T19:44:49.917Z","end":"2026-05-27T19:44:50.025591Z","steps":["trace[1057982689] 'process raft request' (duration: 108.322409ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:50.029574Z","caller":"traceutil/trace.go:171","msg":"trace[65160547] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"105.90956ms","start":"2026-05-27T19:44:49.923629Z","end":"2026-05-27T19:44:50.029539Z","steps":["trace[65160547] 'process raft request' (duration: 105.787306ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:50.029603Z","caller":"traceutil/trace.go:171","msg":"trace[743717716] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"106.581519ms","start":"2026-05-27T19:44:49.922991Z","end":"2026-05-27T19:44:50.029573Z","steps":["trace[743717716] 'process raft request' (duration: 106.314401ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:50.029717Z","caller":"traceutil/trace.go:171","msg":"trace[2130007182] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"105.121537ms","start":"2026-05-27T19:44:49.92457Z","end":"2026-05-27T19:44:50.029692Z","steps":["trace[2130007182] 'process raft request' (duration: 104.87672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:04.167249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.200598ms","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-05-27T19:45:04.167379Z","caller":"traceutil/trace.go:171","msg":"trace[1194490301] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:793; }","duration":"184.401883ms","start":"2026-05-27T19:45:03.982953Z","end":"2026-05-27T19:45:04.167354Z","steps":["trace[1194490301] 'range keys from in-memory index tree' (duration: 183.936381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:04.167352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.754439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-z5264\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-27T19:45:04.167452Z","caller":"traceutil/trace.go:171","msg":"trace[425700650] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-z5264; range_end:; response_count:1; response_revision:793; }","duration":"252.925433ms","start":"2026-05-27T19:45:03.914506Z","end":"2026-05-27T19:45:04.167431Z","steps":["trace[425700650] 'range keys from in-memory index tree' (duration: 252.588145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:23.688079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.56547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:46:23.688372Z","caller":"traceutil/trace.go:171","msg":"trace[490600306] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"391.021801ms","start":"2026-05-27T19:46:23.297316Z","end":"2026-05-27T19:46:23.688338Z","steps":["trace[490600306] 'process raft request' (duration: 30.407172ms)","trace[490600306] 'compare' (duration: 359.369234ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:46:23.688506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:23.2973Z","time spent":"391.151304ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:46:23.688749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.747875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2752449e-0712-4a90-836a-9e52f9081bdc\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-27T19:46:23.688832Z","caller":"traceutil/trace.go:171","msg":"trace[874896583] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1947; }","duration":"364.329673ms","start":"2026-05-27T19:46:23.323946Z","end":"2026-05-27T19:46:23.688275Z","steps":["trace[874896583] 'read index received' (duration: 3.790416ms)","trace[874896583] 'applied index is now lower than readState.Index' (duration: 360.537467ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:23.688887Z","caller":"traceutil/trace.go:171","msg":"trace[348091853] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2752449e-0712-4a90-836a-9e52f9081bdc; range_end:; response_count:1; response_revision:1877; }","duration":"364.970241ms","start":"2026-05-27T19:46:23.323887Z","end":"2026-05-27T19:46:23.688857Z","steps":["trace[348091853] 'agreement among raft nodes before linearized reading' (duration: 364.55618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:23.688954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:23.323865Z","time spent":"365.078344ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2752449e-0712-4a90-836a-9e52f9081bdc\" "} {"level":"warn","ts":"2026-05-27T19:46:23.689216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.049918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:46:23.689308Z","caller":"traceutil/trace.go:171","msg":"trace[1601876559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1877; }","duration":"208.158881ms","start":"2026-05-27T19:46:23.48113Z","end":"2026-05-27T19:46:23.689289Z","steps":["trace[1601876559] 'agreement among raft nodes before linearized reading' (duration: 208.010127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:23.68952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.110011ms","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-05-27T19:46:23.689649Z","caller":"traceutil/trace.go:171","msg":"trace[743207797] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1877; }","duration":"342.244995ms","start":"2026-05-27T19:46:23.347366Z","end":"2026-05-27T19:46:23.689611Z","steps":["trace[743207797] 'agreement among raft nodes before linearized reading' (duration: 341.70154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:23.68975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:23.347348Z","time spent":"342.391309ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T19:46:24.058858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.674248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:46:24.058988Z","caller":"traceutil/trace.go:171","msg":"trace[1653570885] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"212.06008ms","start":"2026-05-27T19:46:23.846917Z","end":"2026-05-27T19:46:24.058977Z","steps":["trace[1653570885] 'process raft request' (duration: 212.013918ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:24.059242Z","caller":"traceutil/trace.go:171","msg":"trace[1988175891] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1950; }","duration":"284.136039ms","start":"2026-05-27T19:46:23.775064Z","end":"2026-05-27T19:46:24.0592Z","steps":["trace[1988175891] 'read index received' (duration: 27.080779ms)","trace[1988175891] 'applied index is now lower than readState.Index' (duration: 257.04978ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:24.059346Z","caller":"traceutil/trace.go:171","msg":"trace[13021971] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"312.730409ms","start":"2026-05-27T19:46:23.746558Z","end":"2026-05-27T19:46:24.059289Z","steps":["trace[13021971] 'process raft request' (duration: 55.552526ms)","trace[13021971] 'compare' (duration: 256.581416ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:46:24.059535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.447307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-27T19:46:24.059556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:23.746539Z","time spent":"312.894113ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10970,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:46:24.059614Z","caller":"traceutil/trace.go:171","msg":"trace[1176414117] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1881; }","duration":"284.5702ms","start":"2026-05-27T19:46:23.775011Z","end":"2026-05-27T19:46:24.059581Z","steps":["trace[1176414117] 'agreement among raft nodes before linearized reading' (duration: 284.394215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:24.059818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.269906ms","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-05-27T19:46:24.059893Z","caller":"traceutil/trace.go:171","msg":"trace[1099920374] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1881; }","duration":"156.368569ms","start":"2026-05-27T19:46:23.903503Z","end":"2026-05-27T19:46:24.059871Z","steps":["trace[1099920374] 'agreement among raft nodes before linearized reading' (duration: 156.128353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:24.060157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.374096ms","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-05-27T19:46:24.060232Z","caller":"traceutil/trace.go:171","msg":"trace[1207490211] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1881; }","duration":"133.51593ms","start":"2026-05-27T19:46:23.926694Z","end":"2026-05-27T19:46:24.06021Z","steps":["trace[1207490211] 'agreement among raft nodes before linearized reading' (duration: 132.940134ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:40.77354Z","caller":"traceutil/trace.go:171","msg":"trace[479643678] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"128.569511ms","start":"2026-05-27T19:46:40.644948Z","end":"2026-05-27T19:46:40.773517Z","steps":["trace[479643678] 'process raft request' (duration: 128.403976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:41.237159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.724823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.104\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-27T19:46:41.237243Z","caller":"traceutil/trace.go:171","msg":"trace[1627533000] range","detail":"{range_begin:/registry/masterleases/199.19.213.104; range_end:; response_count:1; response_revision:2024; }","duration":"104.858467ms","start":"2026-05-27T19:46:41.132365Z","end":"2026-05-27T19:46:41.237224Z","steps":["trace[1627533000] 'range keys from in-memory index tree' (duration: 104.546378ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:41.242265Z","caller":"traceutil/trace.go:171","msg":"trace[1530055327] transaction","detail":"{read_only:false; response_revision:2025; number_of_response:1; }","duration":"105.00348ms","start":"2026-05-27T19:46:41.137234Z","end":"2026-05-27T19:46:41.242237Z","steps":["trace[1530055327] 'process raft request' (duration: 104.716122ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:19.506397Z","caller":"traceutil/trace.go:171","msg":"trace[1554218516] transaction","detail":"{read_only:false; response_revision:2234; number_of_response:1; }","duration":"109.261518ms","start":"2026-05-27T19:47:19.397095Z","end":"2026-05-27T19:47:19.506356Z","steps":["trace[1554218516] 'process raft request' (duration: 109.103004ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:54.234546Z","caller":"traceutil/trace.go:171","msg":"trace[382392816] linearizableReadLoop","detail":"{readStateIndex:2695; appliedIndex:2694; }","duration":"102.500079ms","start":"2026-05-27T19:47:54.132011Z","end":"2026-05-27T19:47:54.234511Z","steps":["trace[382392816] 'read index received' (duration: 102.278703ms)","trace[382392816] 'applied index is now lower than readState.Index' (duration: 216.286µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:47:54.234686Z","caller":"traceutil/trace.go:171","msg":"trace[895346615] transaction","detail":"{read_only:false; response_revision:2597; number_of_response:1; }","duration":"206.613164ms","start":"2026-05-27T19:47:54.028025Z","end":"2026-05-27T19:47:54.234638Z","steps":["trace[895346615] 'process raft request' (duration: 206.280275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:54.23494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.905949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-05-27T19:47:54.235137Z","caller":"traceutil/trace.go:171","msg":"trace[803851121] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2597; }","duration":"103.166597ms","start":"2026-05-27T19:47:54.131941Z","end":"2026-05-27T19:47:54.235107Z","steps":["trace[803851121] 'agreement among raft nodes before linearized reading' (duration: 102.748106ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:54.333436Z","caller":"traceutil/trace.go:171","msg":"trace[32737276] transaction","detail":"{read_only:false; response_revision:2598; number_of_response:1; }","duration":"185.077888ms","start":"2026-05-27T19:47:54.148327Z","end":"2026-05-27T19:47:54.333404Z","steps":["trace[32737276] 'process raft request' (duration: 151.224724ms)","trace[32737276] 'compare' (duration: 33.667719ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:47:54.333796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.520845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-27T19:47:54.333863Z","caller":"traceutil/trace.go:171","msg":"trace[1795434391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2599; }","duration":"196.639838ms","start":"2026-05-27T19:47:54.137209Z","end":"2026-05-27T19:47:54.333849Z","steps":["trace[1795434391] 'agreement among raft nodes before linearized reading' (duration: 196.204695ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:48:28.99443Z","caller":"traceutil/trace.go:171","msg":"trace[1942692842] linearizableReadLoop","detail":"{readStateIndex:2912; appliedIndex:2911; }","duration":"148.419657ms","start":"2026-05-27T19:48:28.845987Z","end":"2026-05-27T19:48:28.994407Z","steps":["trace[1942692842] 'read index received' (duration: 148.18133ms)","trace[1942692842] 'applied index is now lower than readState.Index' (duration: 237.217µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:48:28.994549Z","caller":"traceutil/trace.go:171","msg":"trace[1989068519] transaction","detail":"{read_only:false; response_revision:2806; number_of_response:1; }","duration":"165.999302ms","start":"2026-05-27T19:48:28.828517Z","end":"2026-05-27T19:48:28.994516Z","steps":["trace[1989068519] 'process raft request' (duration: 165.733705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:48:28.994703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.693504ms","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-05-27T19:48:28.994875Z","caller":"traceutil/trace.go:171","msg":"trace[1907384759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2806; }","duration":"148.880509ms","start":"2026-05-27T19:48:28.845971Z","end":"2026-05-27T19:48:28.994852Z","steps":["trace[1907384759] 'agreement among raft nodes before linearized reading' (duration: 148.54018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:51:05.337889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.473035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:51:05.338159Z","caller":"traceutil/trace.go:171","msg":"trace[725704804] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:3831; }","duration":"110.646039ms","start":"2026-05-27T19:51:05.227324Z","end":"2026-05-27T19:51:05.33797Z","steps":["trace[725704804] 'count revisions from in-memory index tree' (duration: 110.392963ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:52:31.431063Z","caller":"traceutil/trace.go:171","msg":"trace[1287287400] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"142.9856ms","start":"2026-05-27T19:52:31.288051Z","end":"2026-05-27T19:52:31.431037Z","steps":["trace[1287287400] 'process raft request' (duration: 142.854367ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:52:31.431142Z","caller":"traceutil/trace.go:171","msg":"trace[166397494] transaction","detail":"{read_only:false; response_revision:5018; number_of_response:1; }","duration":"144.77756ms","start":"2026-05-27T19:52:31.286311Z","end":"2026-05-27T19:52:31.431088Z","steps":["trace[166397494] 'process raft request' (duration: 114.286511ms)","trace[166397494] 'compare' (duration: 30.072617ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:52:45.041914Z","caller":"traceutil/trace.go:171","msg":"trace[1188426986] transaction","detail":"{read_only:false; response_revision:5279; number_of_response:1; }","duration":"100.951466ms","start":"2026-05-27T19:52:44.940927Z","end":"2026-05-27T19:52:45.041878Z","steps":["trace[1188426986] 'compare' (duration: 89.967793ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:52:45.697815Z","caller":"traceutil/trace.go:171","msg":"trace[354752979] linearizableReadLoop","detail":"{readStateIndex:5479; appliedIndex:5478; }","duration":"218.381323ms","start":"2026-05-27T19:52:45.479402Z","end":"2026-05-27T19:52:45.697783Z","steps":["trace[354752979] 'read index received' (duration: 39.786904ms)","trace[354752979] 'applied index is now lower than readState.Index' (duration: 178.592399ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:52:45.69814Z","caller":"traceutil/trace.go:171","msg":"trace[1092369112] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"224.804019ms","start":"2026-05-27T19:52:45.473319Z","end":"2026-05-27T19:52:45.698123Z","steps":["trace[1092369112] 'process raft request' (duration: 138.450875ms)","trace[1092369112] 'compare' (duration: 85.875161ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:52:45.698294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.851145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:52:45.698425Z","caller":"traceutil/trace.go:171","msg":"trace[1377931231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5281; }","duration":"219.069732ms","start":"2026-05-27T19:52:45.479328Z","end":"2026-05-27T19:52:45.698398Z","steps":["trace[1377931231] 'agreement among raft nodes before linearized reading' (duration: 218.817535ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:52:45.733569Z","caller":"traceutil/trace.go:171","msg":"trace[457656122] transaction","detail":"{read_only:false; response_revision:5282; number_of_response:1; }","duration":"209.042706ms","start":"2026-05-27T19:52:45.524495Z","end":"2026-05-27T19:52:45.733538Z","steps":["trace[457656122] 'process raft request' (duration: 208.751318ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:52:45.734623Z","caller":"traceutil/trace.go:171","msg":"trace[952057339] transaction","detail":"{read_only:false; response_revision:5283; number_of_response:1; }","duration":"209.18398ms","start":"2026-05-27T19:52:45.525348Z","end":"2026-05-27T19:52:45.734532Z","steps":["trace[952057339] 'process raft request' (duration: 208.017707ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:52:45.736383Z","caller":"traceutil/trace.go:171","msg":"trace[486992803] transaction","detail":"{read_only:false; response_revision:5284; number_of_response:1; }","duration":"210.901886ms","start":"2026-05-27T19:52:45.525464Z","end":"2026-05-27T19:52:45.736366Z","steps":["trace[486992803] 'process raft request' (duration: 208.750828ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:52:45.736801Z","caller":"traceutil/trace.go:171","msg":"trace[1552971818] transaction","detail":"{read_only:false; response_revision:5285; number_of_response:1; }","duration":"211.258357ms","start":"2026-05-27T19:52:45.52553Z","end":"2026-05-27T19:52:45.736788Z","steps":["trace[1552971818] 'process raft request' (duration: 210.192857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:52:45.738278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.783385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rdn65\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-27T19:52:45.738339Z","caller":"traceutil/trace.go:171","msg":"trace[1841598329] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rdn65; range_end:; response_count:1; response_revision:5285; }","duration":"221.91777ms","start":"2026-05-27T19:52:45.516403Z","end":"2026-05-27T19:52:45.738321Z","steps":["trace[1841598329] 'agreement among raft nodes before linearized reading' (duration: 221.097647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:52:45.738767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.700333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/goldpinger-7jdm4\" ","response":"range_response_count:1 size:5199"} {"level":"info","ts":"2026-05-27T19:52:45.738828Z","caller":"traceutil/trace.go:171","msg":"trace[814414063] range","detail":"{range_begin:/registry/pods/monitoring/goldpinger-7jdm4; range_end:; response_count:1; response_revision:5285; }","duration":"213.788156ms","start":"2026-05-27T19:52:45.525025Z","end":"2026-05-27T19:52:45.738813Z","steps":["trace[814414063] 'agreement among raft nodes before linearized reading' (duration: 212.270565ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:52:53.655047Z","caller":"traceutil/trace.go:171","msg":"trace[289926554] transaction","detail":"{read_only:false; response_revision:5483; number_of_response:1; }","duration":"117.287925ms","start":"2026-05-27T19:52:53.537724Z","end":"2026-05-27T19:52:53.655012Z","steps":["trace[289926554] 'process raft request' (duration: 117.042678ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:54:07.978221Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3021} {"level":"info","ts":"2026-05-27T19:54:08.0603Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3021,"took":"80.410749ms","hash":3168971059,"current-db-size-bytes":57221120,"current-db-size":"57 MB","current-db-size-in-use-bytes":31969280,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-27T19:54:08.060361Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3168971059,"revision":3021,"compact-revision":-1} {"level":"warn","ts":"2026-05-27T19:58:56.938305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.092674ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-27T19:58:56.938398Z","caller":"traceutil/trace.go:171","msg":"trace[1245323741] linearizableReadLoop","detail":"{readStateIndex:8547; appliedIndex:8546; }","duration":"381.104073ms","start":"2026-05-27T19:58:56.557281Z","end":"2026-05-27T19:58:56.938385Z","steps":["trace[1245323741] 'read index received' (duration: 218.945357ms)","trace[1245323741] 'applied index is now lower than readState.Index' (duration: 162.157466ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:58:56.938705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.424541ms","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-05-27T19:58:56.938751Z","caller":"traceutil/trace.go:171","msg":"trace[1797427372] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8254; }","duration":"381.501923ms","start":"2026-05-27T19:58:56.557227Z","end":"2026-05-27T19:58:56.938729Z","steps":["trace[1797427372] 'agreement among raft nodes before linearized reading' (duration: 381.3752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:58:56.938775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:58:56.557209Z","time spent":"381.560424ms","remote":"127.0.0.1:48510","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":"warn","ts":"2026-05-27T19:58:56.938822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.592056ms","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-05-27T19:58:56.938912Z","caller":"traceutil/trace.go:171","msg":"trace[91563615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8254; }","duration":"373.709669ms","start":"2026-05-27T19:58:56.565181Z","end":"2026-05-27T19:58:56.938891Z","steps":["trace[91563615] 'agreement among raft nodes before linearized reading' (duration: 373.483473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:58:56.938958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:58:56.565172Z","time spent":"373.777161ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T19:58:56.938974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.892967ms","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-05-27T19:58:56.939103Z","caller":"traceutil/trace.go:171","msg":"trace[828975716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8254; }","duration":"377.074821ms","start":"2026-05-27T19:58:56.562003Z","end":"2026-05-27T19:58:56.939078Z","steps":["trace[828975716] 'agreement among raft nodes before linearized reading' (duration: 376.66611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:58:56.939167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:58:56.561993Z","time spent":"377.164834ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T19:59:07.990605Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6125} {"level":"info","ts":"2026-05-27T19:59:08.398151Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6125,"took":"404.368523ms","hash":45252071,"current-db-size-bytes":57221120,"current-db-size":"57 MB","current-db-size-in-use-bytes":23891968,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-27T19:59:08.398236Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":45252071,"revision":6125,"compact-revision":3021} {"level":"warn","ts":"2026-05-27T19:59:09.520865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.620189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-27T19:59:09.520939Z","caller":"traceutil/trace.go:171","msg":"trace[1073228293] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:8322; }","duration":"150.738902ms","start":"2026-05-27T19:59:09.370182Z","end":"2026-05-27T19:59:09.520921Z","steps":["trace[1073228293] 'range keys from in-memory index tree' (duration: 150.455414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:59:09.521236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.719432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:123413"} {"level":"info","ts":"2026-05-27T19:59:09.521317Z","caller":"traceutil/trace.go:171","msg":"trace[510928230] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:8322; }","duration":"150.807514ms","start":"2026-05-27T19:59:09.370493Z","end":"2026-05-27T19:59:09.5213Z","steps":["trace[510928230] 'range keys from in-memory index tree' (duration: 150.458385ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:59:37.790858Z","caller":"traceutil/trace.go:171","msg":"trace[364558549] linearizableReadLoop","detail":"{readStateIndex:8832; appliedIndex:8831; }","duration":"311.87742ms","start":"2026-05-27T19:59:37.478955Z","end":"2026-05-27T19:59:37.790832Z","steps":["trace[364558549] 'read index received' (duration: 311.744566ms)","trace[364558549] 'applied index is now lower than readState.Index' (duration: 131.824µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:59:37.791004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.038745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:59:37.791045Z","caller":"traceutil/trace.go:171","msg":"trace[1086257266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8527; }","duration":"312.127297ms","start":"2026-05-27T19:59:37.478907Z","end":"2026-05-27T19:59:37.791034Z","steps":["trace[1086257266] 'agreement among raft nodes before linearized reading' (duration: 312.049975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:59:37.791079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:59:37.478889Z","time spent":"312.181998ms","remote":"127.0.0.1:48180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T19:59:37.791191Z","caller":"traceutil/trace.go:171","msg":"trace[1676098555] transaction","detail":"{read_only:false; response_revision:8527; number_of_response:1; }","duration":"459.204449ms","start":"2026-05-27T19:59:37.331957Z","end":"2026-05-27T19:59:37.791162Z","steps":["trace[1676098555] 'process raft request' (duration: 458.725236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:59:37.791681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.989831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:59:37.79238Z","caller":"traceutil/trace.go:171","msg":"trace[756240116] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:8528; }","duration":"289.788633ms","start":"2026-05-27T19:59:37.502565Z","end":"2026-05-27T19:59:37.792353Z","steps":["trace[756240116] 'agreement among raft nodes before linearized reading' (duration: 289.001792ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:59:37.791718Z","caller":"traceutil/trace.go:171","msg":"trace[2108495231] transaction","detail":"{read_only:false; response_revision:8528; number_of_response:1; }","duration":"106.498577ms","start":"2026-05-27T19:59:37.685149Z","end":"2026-05-27T19:59:37.791648Z","steps":["trace[2108495231] 'process raft request' (duration: 106.304982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:59:37.793231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:59:37.331932Z","time spent":"459.99103ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:59:40.320842Z","caller":"traceutil/trace.go:171","msg":"trace[1149601117] linearizableReadLoop","detail":"{readStateIndex:8848; appliedIndex:8847; }","duration":"222.030141ms","start":"2026-05-27T19:59:40.098791Z","end":"2026-05-27T19:59:40.320821Z","steps":["trace[1149601117] 'read index received' (duration: 221.910718ms)","trace[1149601117] 'applied index is now lower than readState.Index' (duration: 118.403µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:59:40.320913Z","caller":"traceutil/trace.go:171","msg":"trace[884883205] transaction","detail":"{read_only:false; response_revision:8543; number_of_response:1; }","duration":"247.649235ms","start":"2026-05-27T19:59:40.073214Z","end":"2026-05-27T19:59:40.320863Z","steps":["trace[884883205] 'process raft request' (duration: 247.478201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:59:40.321041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.225286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rdn65\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-27T19:59:40.321083Z","caller":"traceutil/trace.go:171","msg":"trace[1051488926] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rdn65; range_end:; response_count:1; response_revision:8543; }","duration":"222.311999ms","start":"2026-05-27T19:59:40.09876Z","end":"2026-05-27T19:59:40.321072Z","steps":["trace[1051488926] 'agreement among raft nodes before linearized reading' (duration: 222.157935ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:59:40.405558Z","caller":"traceutil/trace.go:171","msg":"trace[640721422] transaction","detail":"{read_only:false; response_revision:8544; number_of_response:1; }","duration":"267.11266ms","start":"2026-05-27T19:59:40.1384Z","end":"2026-05-27T19:59:40.405513Z","steps":["trace[640721422] 'process raft request' (duration: 210.237848ms)","trace[640721422] 'compare' (duration: 56.703397ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:59:50.129247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.683041ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:59:50.129395Z","caller":"traceutil/trace.go:171","msg":"trace[1482338402] transaction","detail":"{read_only:false; response_revision:8633; number_of_response:1; }","duration":"263.238919ms","start":"2026-05-27T19:59:49.866137Z","end":"2026-05-27T19:59:50.129376Z","steps":["trace[1482338402] 'process raft request' (duration: 127.346663ms)","trace[1482338402] 'compare' (duration: 135.470945ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:59:50.173464Z","caller":"traceutil/trace.go:171","msg":"trace[1683279185] linearizableReadLoop","detail":"{readStateIndex:8944; appliedIndex:8942; }","duration":"251.505067ms","start":"2026-05-27T19:59:49.921919Z","end":"2026-05-27T19:59:50.173425Z","steps":["trace[1683279185] 'read index received' (duration: 71.525527ms)","trace[1683279185] 'applied index is now lower than readState.Index' (duration: 179.97774ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:59:50.173893Z","caller":"traceutil/trace.go:171","msg":"trace[1216131976] transaction","detail":"{read_only:false; response_revision:8634; number_of_response:1; }","duration":"264.706709ms","start":"2026-05-27T19:59:49.909169Z","end":"2026-05-27T19:59:50.173876Z","steps":["trace[1216131976] 'process raft request' (duration: 263.915078ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:59:50.174247Z","caller":"traceutil/trace.go:171","msg":"trace[1292352674] transaction","detail":"{read_only:false; response_revision:8635; number_of_response:1; }","duration":"251.749023ms","start":"2026-05-27T19:59:49.922478Z","end":"2026-05-27T19:59:50.174227Z","steps":["trace[1292352674] 'process raft request' (duration: 250.845098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:59:50.174581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.625177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-78fzv\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-05-27T19:59:50.174634Z","caller":"traceutil/trace.go:171","msg":"trace[2096478903] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-78fzv; range_end:; response_count:1; response_revision:8635; }","duration":"252.746382ms","start":"2026-05-27T19:59:49.921874Z","end":"2026-05-27T19:59:50.17462Z","steps":["trace[2096478903] 'agreement among raft nodes before linearized reading' (duration: 252.530715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:59:50.175055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.274469ms","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-05-27T19:59:50.175146Z","caller":"traceutil/trace.go:171","msg":"trace[800974360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8635; }","duration":"108.431752ms","start":"2026-05-27T19:59:50.066693Z","end":"2026-05-27T19:59:50.175125Z","steps":["trace[800974360] 'agreement among raft nodes before linearized reading' (duration: 108.188736ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:59:50.308127Z","caller":"traceutil/trace.go:171","msg":"trace[800524338] transaction","detail":"{read_only:false; response_revision:8636; number_of_response:1; }","duration":"128.500565ms","start":"2026-05-27T19:59:50.179577Z","end":"2026-05-27T19:59:50.308077Z","steps":["trace[800524338] 'process raft request' (duration: 64.407241ms)","trace[800524338] 'compare' (duration: 63.896728ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:59:50.325086Z","caller":"traceutil/trace.go:171","msg":"trace[1143143642] transaction","detail":"{read_only:false; response_revision:8637; number_of_response:1; }","duration":"139.426335ms","start":"2026-05-27T19:59:50.185613Z","end":"2026-05-27T19:59:50.325039Z","steps":["trace[1143143642] 'process raft request' (duration: 139.308211ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:00:26.301465Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002459.wal"} {"level":"warn","ts":"2026-05-27T20:01:24.771423Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11554721976223759587,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T20:01:24.833011Z","caller":"traceutil/trace.go:171","msg":"trace[1420401062] transaction","detail":"{read_only:false; response_revision:9463; number_of_response:1; }","duration":"565.849333ms","start":"2026-05-27T20:01:24.267117Z","end":"2026-05-27T20:01:24.832966Z","steps":["trace[1420401062] 'process raft request' (duration: 565.628007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:01:24.833341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:24.267103Z","time spent":"566.101709ms","remote":"127.0.0.1:48510","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-05-27T20:01:24.835615Z","caller":"traceutil/trace.go:171","msg":"trace[1980929482] transaction","detail":"{read_only:false; response_revision:9464; number_of_response:1; }","duration":"564.445433ms","start":"2026-05-27T20:01:24.271137Z","end":"2026-05-27T20:01:24.835582Z","steps":["trace[1980929482] 'process raft request' (duration: 564.230187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:01:24.835858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:24.271114Z","time spent":"564.600047ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:01:24.835867Z","caller":"traceutil/trace.go:171","msg":"trace[262976305] transaction","detail":"{read_only:false; response_revision:9465; number_of_response:1; }","duration":"564.645509ms","start":"2026-05-27T20:01:24.271203Z","end":"2026-05-27T20:01:24.835849Z","steps":["trace[262976305] 'process raft request' (duration: 564.31745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:01:24.835954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:24.2712Z","time spent":"564.708001ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:01:24.836044Z","caller":"traceutil/trace.go:171","msg":"trace[1834173902] transaction","detail":"{read_only:false; response_revision:9466; number_of_response:1; }","duration":"564.799633ms","start":"2026-05-27T20:01:24.271233Z","end":"2026-05-27T20:01:24.836032Z","steps":["trace[1834173902] 'process raft request' (duration: 564.494125ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:01:24.836056Z","caller":"traceutil/trace.go:171","msg":"trace[345816661] transaction","detail":"{read_only:false; response_revision:9468; number_of_response:1; }","duration":"564.71356ms","start":"2026-05-27T20:01:24.271332Z","end":"2026-05-27T20:01:24.836045Z","steps":["trace[345816661] 'process raft request' (duration: 564.560666ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:01:24.836039Z","caller":"traceutil/trace.go:171","msg":"trace[505740317] transaction","detail":"{read_only:false; response_revision:9467; number_of_response:1; }","duration":"564.745091ms","start":"2026-05-27T20:01:24.271266Z","end":"2026-05-27T20:01:24.836011Z","steps":["trace[505740317] 'process raft request' (duration: 564.583997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:01:24.836107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:24.271228Z","time spent":"564.841824ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:01:24.836117Z","caller":"traceutil/trace.go:171","msg":"trace[994443555] transaction","detail":"{read_only:false; response_revision:9469; number_of_response:1; }","duration":"564.538416ms","start":"2026-05-27T20:01:24.271569Z","end":"2026-05-27T20:01:24.836107Z","steps":["trace[994443555] 'process raft request' (duration: 564.356821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:01:24.836209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:24.271263Z","time spent":"564.851854ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:01:24.836231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:24.271536Z","time spent":"564.618287ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:01:24.836048Z","caller":"traceutil/trace.go:171","msg":"trace[479984680] transaction","detail":"{read_only:false; response_revision:9470; number_of_response:1; }","duration":"564.075783ms","start":"2026-05-27T20:01:24.271946Z","end":"2026-05-27T20:01:24.836022Z","steps":["trace[479984680] 'process raft request' (duration: 564.013981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:01:24.836344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:24.271938Z","time spent":"564.360902ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:01:24.836245Z","caller":"traceutil/trace.go:171","msg":"trace[412337638] transaction","detail":"{read_only:false; response_revision:9471; number_of_response:1; }","duration":"555.389195ms","start":"2026-05-27T20:01:24.280832Z","end":"2026-05-27T20:01:24.836221Z","steps":["trace[412337638] 'process raft request' (duration: 555.155948ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:01:24.836435Z","caller":"traceutil/trace.go:171","msg":"trace[985495863] transaction","detail":"{read_only:false; response_revision:9472; number_of_response:1; }","duration":"111.448216ms","start":"2026-05-27T20:01:24.724937Z","end":"2026-05-27T20:01:24.836385Z","steps":["trace[985495863] 'process raft request' (duration: 111.210268ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:01:24.836428Z","caller":"traceutil/trace.go:171","msg":"trace[608879755] linearizableReadLoop","detail":"{readStateIndex:9806; appliedIndex:9796; }","duration":"565.574874ms","start":"2026-05-27T20:01:24.270801Z","end":"2026-05-27T20:01:24.836376Z","steps":["trace[608879755] 'read index received' (duration: 561.914133ms)","trace[608879755] 'applied index is now lower than readState.Index' (duration: 3.659521ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:01:24.836657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:24.280811Z","time spent":"555.678233ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:01:24.83668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.79484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T20:01:24.836723Z","caller":"traceutil/trace.go:171","msg":"trace[114933122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9472; }","duration":"565.845202ms","start":"2026-05-27T20:01:24.270866Z","end":"2026-05-27T20:01:24.836711Z","steps":["trace[114933122] 'agreement among raft nodes before linearized reading' (duration: 565.76772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:01:24.836765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:24.270863Z","time spent":"565.896084ms","remote":"127.0.0.1:48180","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-05-27T20:01:24.836777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.608148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"warn","ts":"2026-05-27T20:01:24.836795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.958181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-27T20:01:24.836855Z","caller":"traceutil/trace.go:171","msg":"trace[1484188441] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:9472; }","duration":"556.033753ms","start":"2026-05-27T20:01:24.280804Z","end":"2026-05-27T20:01:24.836838Z","steps":["trace[1484188441] 'agreement among raft nodes before linearized reading' (duration: 555.843878ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:01:24.836851Z","caller":"traceutil/trace.go:171","msg":"trace[963796714] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:9472; }","duration":"472.715031ms","start":"2026-05-27T20:01:24.364092Z","end":"2026-05-27T20:01:24.836807Z","steps":["trace[963796714] 'agreement among raft nodes before linearized reading' (duration: 472.536896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:01:24.83689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:24.280787Z","time spent":"556.096005ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-05-27T20:01:24.836899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:24.364074Z","time spent":"472.814974ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-05-27T20:01:24.83692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.12287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:31 size:291601"} {"level":"info","ts":"2026-05-27T20:01:24.836979Z","caller":"traceutil/trace.go:171","msg":"trace[1405772859] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:31; response_revision:9472; }","duration":"566.209472ms","start":"2026-05-27T20:01:24.270755Z","end":"2026-05-27T20:01:24.836964Z","steps":["trace[1405772859] 'agreement among raft nodes before linearized reading' (duration: 565.726809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:01:24.837033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:24.270741Z","time spent":"566.279754ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":31,"response size":291624,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-05-27T20:01:24.836118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:24.271328Z","time spent":"564.760621ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:01:44.854721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.932154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T20:01:44.854827Z","caller":"traceutil/trace.go:171","msg":"trace[1462095414] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:9596; }","duration":"334.084638ms","start":"2026-05-27T20:01:44.52072Z","end":"2026-05-27T20:01:44.854805Z","steps":["trace[1462095414] 'count revisions from in-memory index tree' (duration: 333.858931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:01:44.854875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:44.520704Z","time spent":"334.154759ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-27T20:01:45.925358Z","caller":"traceutil/trace.go:171","msg":"trace[571021812] transaction","detail":"{read_only:false; response_revision:9609; number_of_response:1; }","duration":"442.614533ms","start":"2026-05-27T20:01:45.482722Z","end":"2026-05-27T20:01:45.925337Z","steps":["trace[571021812] 'process raft request' (duration: 442.475869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:01:45.92551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:45.482698Z","time spent":"442.737397ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:01:45.929503Z","caller":"traceutil/trace.go:171","msg":"trace[59493619] linearizableReadLoop","detail":"{readStateIndex:9948; appliedIndex:9946; }","duration":"286.51411ms","start":"2026-05-27T20:01:45.642932Z","end":"2026-05-27T20:01:45.929446Z","steps":["trace[59493619] 'read index received' (duration: 282.295674ms)","trace[59493619] 'applied index is now lower than readState.Index' (duration: 4.217496ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:01:45.930324Z","caller":"traceutil/trace.go:171","msg":"trace[1909475460] transaction","detail":"{read_only:false; response_revision:9610; number_of_response:1; }","duration":"338.939402ms","start":"2026-05-27T20:01:45.59135Z","end":"2026-05-27T20:01:45.93029Z","steps":["trace[1909475460] 'process raft request' (duration: 337.966115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:01:45.930474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:45.591332Z","time spent":"339.044225ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19206,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:01:45.931478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.476854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/glance-rabbit-init-4drmb\" ","response":"range_response_count:1 size:6412"} {"level":"info","ts":"2026-05-27T20:01:45.931539Z","caller":"traceutil/trace.go:171","msg":"trace[128830686] range","detail":"{range_begin:/registry/pods/openstack/glance-rabbit-init-4drmb; range_end:; response_count:1; response_revision:9610; }","duration":"288.627118ms","start":"2026-05-27T20:01:45.642897Z","end":"2026-05-27T20:01:45.931524Z","steps":["trace[128830686] 'agreement among raft nodes before linearized reading' (duration: 286.675704ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:01:47.348377Z","caller":"traceutil/trace.go:171","msg":"trace[1397294387] transaction","detail":"{read_only:false; response_revision:9612; number_of_response:1; }","duration":"442.403257ms","start":"2026-05-27T20:01:46.905951Z","end":"2026-05-27T20:01:47.348354Z","steps":["trace[1397294387] 'process raft request' (duration: 442.249633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:01:47.34853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:46.905918Z","time spent":"442.533901ms","remote":"127.0.0.1:48510","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-05-27T20:01:47.366123Z","caller":"traceutil/trace.go:171","msg":"trace[713258951] transaction","detail":"{read_only:false; response_revision:9614; number_of_response:1; }","duration":"456.220367ms","start":"2026-05-27T20:01:46.909868Z","end":"2026-05-27T20:01:47.366088Z","steps":["trace[713258951] 'process raft request' (duration: 456.102244ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:01:47.366146Z","caller":"traceutil/trace.go:171","msg":"trace[1478536195] transaction","detail":"{read_only:false; response_revision:9613; number_of_response:1; }","duration":"456.284049ms","start":"2026-05-27T20:01:46.909782Z","end":"2026-05-27T20:01:47.366066Z","steps":["trace[1478536195] 'process raft request' (duration: 455.975971ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:01:47.3663Z","caller":"traceutil/trace.go:171","msg":"trace[93827390] linearizableReadLoop","detail":"{readStateIndex:9954; appliedIndex:9950; }","duration":"426.658814ms","start":"2026-05-27T20:01:46.939618Z","end":"2026-05-27T20:01:47.366277Z","steps":["trace[93827390] 'read index received' (duration: 408.641009ms)","trace[93827390] 'applied index is now lower than readState.Index' (duration: 18.015895ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:01:47.366324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:46.909861Z","time spent":"456.370252ms","remote":"127.0.0.1:48510","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-05-27T20:01:47.366332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:46.909761Z","time spent":"456.481484ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:01:47.366403Z","caller":"traceutil/trace.go:171","msg":"trace[1595443786] transaction","detail":"{read_only:false; response_revision:9615; number_of_response:1; }","duration":"449.676837ms","start":"2026-05-27T20:01:46.916716Z","end":"2026-05-27T20:01:47.366392Z","steps":["trace[1595443786] 'process raft request' (duration: 449.311667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:01:47.36652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:46.916704Z","time spent":"449.724879ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:01:47.366552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.69096ms","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-05-27T20:01:47.366585Z","caller":"traceutil/trace.go:171","msg":"trace[1781092633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9615; }","duration":"420.721901ms","start":"2026-05-27T20:01:46.945853Z","end":"2026-05-27T20:01:47.366575Z","steps":["trace[1781092633] 'agreement among raft nodes before linearized reading' (duration: 420.603618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:01:47.366624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:46.945818Z","time spent":"420.799313ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T20:01:47.366615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.198871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-27T20:01:47.366703Z","caller":"traceutil/trace.go:171","msg":"trace[1271095607] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9615; }","duration":"426.315865ms","start":"2026-05-27T20:01:46.940367Z","end":"2026-05-27T20:01:47.366683Z","steps":["trace[1271095607] 'agreement among raft nodes before linearized reading' (duration: 426.086699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:01:47.366745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:46.94036Z","time spent":"426.377017ms","remote":"127.0.0.1:48510","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-05-27T20:01:47.366899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.356653ms","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-05-27T20:01:47.366967Z","caller":"traceutil/trace.go:171","msg":"trace[1851451755] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9615; }","duration":"423.466206ms","start":"2026-05-27T20:01:46.943487Z","end":"2026-05-27T20:01:47.366953Z","steps":["trace[1851451755] 'agreement among raft nodes before linearized reading' (duration: 423.277011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:01:47.366909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.581957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-27T20:01:47.367009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:46.94348Z","time spent":"423.519378ms","remote":"127.0.0.1:48510","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-05-27T20:01:47.367018Z","caller":"traceutil/trace.go:171","msg":"trace[212749339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9615; }","duration":"424.723081ms","start":"2026-05-27T20:01:46.942279Z","end":"2026-05-27T20:01:47.367002Z","steps":["trace[212749339] 'agreement among raft nodes before linearized reading' (duration: 424.514695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:01:47.367052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:46.942272Z","time spent":"424.772452ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T20:01:47.367039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.50628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rdn65\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-27T20:01:47.367118Z","caller":"traceutil/trace.go:171","msg":"trace[683767622] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rdn65; range_end:; response_count:1; response_revision:9615; }","duration":"414.585543ms","start":"2026-05-27T20:01:46.95252Z","end":"2026-05-27T20:01:47.367105Z","steps":["trace[683767622] 'agreement among raft nodes before linearized reading' (duration: 414.213692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:01:47.367125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.459076ms","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-05-27T20:01:47.367211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:46.952498Z","time spent":"414.678744ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-rdn65\" "} {"level":"info","ts":"2026-05-27T20:01:47.367228Z","caller":"traceutil/trace.go:171","msg":"trace[1794087785] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9615; }","duration":"427.61626ms","start":"2026-05-27T20:01:46.939591Z","end":"2026-05-27T20:01:47.367207Z","steps":["trace[1794087785] 'agreement among raft nodes before linearized reading' (duration: 427.065685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:01:47.367275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:01:46.939581Z","time spent":"427.685742ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T20:01:52.261135Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8a0c9a032e61a05a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-27T20:01:52.266677Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-27T20:01:52.266741Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-27T20:02:43.794816Z","caller":"traceutil/trace.go:171","msg":"trace[230111671] transaction","detail":"{read_only:false; response_revision:9993; number_of_response:1; }","duration":"250.846726ms","start":"2026-05-27T20:02:43.543939Z","end":"2026-05-27T20:02:43.794785Z","steps":["trace[230111671] 'process raft request' (duration: 250.679362ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:02:43.804299Z","caller":"traceutil/trace.go:171","msg":"trace[812684530] linearizableReadLoop","detail":"{readStateIndex:10347; appliedIndex:10343; }","duration":"167.155135ms","start":"2026-05-27T20:02:43.63712Z","end":"2026-05-27T20:02:43.804276Z","steps":["trace[812684530] 'read index received' (duration: 157.607503ms)","trace[812684530] 'applied index is now lower than readState.Index' (duration: 9.546682ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:02:43.804338Z","caller":"traceutil/trace.go:171","msg":"trace[1318663034] transaction","detail":"{read_only:false; response_revision:9995; number_of_response:1; }","duration":"247.176206ms","start":"2026-05-27T20:02:43.557134Z","end":"2026-05-27T20:02:43.80431Z","steps":["trace[1318663034] 'process raft request' (duration: 247.062033ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:02:43.804415Z","caller":"traceutil/trace.go:171","msg":"trace[1530080658] transaction","detail":"{read_only:false; response_revision:9996; number_of_response:1; }","duration":"245.15956ms","start":"2026-05-27T20:02:43.55924Z","end":"2026-05-27T20:02:43.8044Z","steps":["trace[1530080658] 'process raft request' (duration: 244.990626ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:02:43.804491Z","caller":"traceutil/trace.go:171","msg":"trace[674448883] transaction","detail":"{read_only:false; response_revision:9994; number_of_response:1; }","duration":"259.32674ms","start":"2026-05-27T20:02:43.545121Z","end":"2026-05-27T20:02:43.804448Z","steps":["trace[674448883] 'process raft request' (duration: 258.995771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:02:43.804648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.298693ms","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-05-27T20:02:43.804686Z","caller":"traceutil/trace.go:171","msg":"trace[1943676406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9996; }","duration":"158.353215ms","start":"2026-05-27T20:02:43.646324Z","end":"2026-05-27T20:02:43.804677Z","steps":["trace[1943676406] 'agreement among raft nodes before linearized reading' (duration: 158.215631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:02:43.804713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.778703ms","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-05-27T20:02:43.804765Z","caller":"traceutil/trace.go:171","msg":"trace[1312694109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9996; }","duration":"159.855945ms","start":"2026-05-27T20:02:43.644895Z","end":"2026-05-27T20:02:43.804751Z","steps":["trace[1312694109] 'agreement among raft nodes before linearized reading' (duration: 159.716491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:02:43.804849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.721312ms","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-05-27T20:02:43.804844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.688516ms","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-05-27T20:02:43.804904Z","caller":"traceutil/trace.go:171","msg":"trace[173684822] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9996; }","duration":"167.817135ms","start":"2026-05-27T20:02:43.637075Z","end":"2026-05-27T20:02:43.804892Z","steps":["trace[173684822] 'agreement among raft nodes before linearized reading' (duration: 167.65682ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:02:43.804927Z","caller":"traceutil/trace.go:171","msg":"trace[457055194] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9996; }","duration":"161.805399ms","start":"2026-05-27T20:02:43.643102Z","end":"2026-05-27T20:02:43.804907Z","steps":["trace[457055194] 'agreement among raft nodes before linearized reading' (duration: 161.554402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:02:43.804633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.264692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-27T20:02:43.805243Z","caller":"traceutil/trace.go:171","msg":"trace[157686671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9996; }","duration":"166.91962ms","start":"2026-05-27T20:02:43.63831Z","end":"2026-05-27T20:02:43.805229Z","steps":["trace[157686671] 'agreement among raft nodes before linearized reading' (duration: 166.219821ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:04:07.999566Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8315} {"level":"info","ts":"2026-05-27T20:04:08.080543Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8315,"took":"77.52912ms","hash":1590982510,"current-db-size-bytes":57221120,"current-db-size":"57 MB","current-db-size-in-use-bytes":24485888,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-27T20:04:08.080623Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1590982510,"revision":8315,"compact-revision":6125} {"level":"info","ts":"2026-05-27T20:09:08.006Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10522} {"level":"info","ts":"2026-05-27T20:09:08.086881Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10522,"took":"77.038436ms","hash":2354804898,"current-db-size-bytes":57221120,"current-db-size":"57 MB","current-db-size-in-use-bytes":26292224,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-27T20:09:08.087003Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2354804898,"revision":10522,"compact-revision":8315} {"level":"warn","ts":"2026-05-27T20:13:43.303061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.585057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rdn65\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-27T20:13:43.303242Z","caller":"traceutil/trace.go:171","msg":"trace[1954560519] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rdn65; range_end:; response_count:1; response_revision:14760; }","duration":"225.829823ms","start":"2026-05-27T20:13:43.077389Z","end":"2026-05-27T20:13:43.303218Z","steps":["trace[1954560519] 'range keys from in-memory index tree' (duration: 225.419502ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:13:43.447308Z","caller":"traceutil/trace.go:171","msg":"trace[162689916] transaction","detail":"{read_only:false; response_revision:14761; number_of_response:1; }","duration":"139.572225ms","start":"2026-05-27T20:13:43.307708Z","end":"2026-05-27T20:13:43.447281Z","steps":["trace[162689916] 'process raft request' (duration: 139.404871ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:13:43.452166Z","caller":"traceutil/trace.go:171","msg":"trace[877482888] transaction","detail":"{read_only:false; response_revision:14762; number_of_response:1; }","duration":"144.381406ms","start":"2026-05-27T20:13:43.307737Z","end":"2026-05-27T20:13:43.452118Z","steps":["trace[877482888] 'process raft request' (duration: 144.17206ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:13:43.452269Z","caller":"traceutil/trace.go:171","msg":"trace[752465120] transaction","detail":"{read_only:false; response_revision:14763; number_of_response:1; }","duration":"144.245182ms","start":"2026-05-27T20:13:43.30799Z","end":"2026-05-27T20:13:43.452235Z","steps":["trace[752465120] 'process raft request' (duration: 144.052987ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:13:43.452583Z","caller":"traceutil/trace.go:171","msg":"trace[1723764159] transaction","detail":"{read_only:false; response_revision:14764; number_of_response:1; }","duration":"144.475629ms","start":"2026-05-27T20:13:43.308093Z","end":"2026-05-27T20:13:43.452569Z","steps":["trace[1723764159] 'process raft request' (duration: 143.986155ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:13:43.452729Z","caller":"traceutil/trace.go:171","msg":"trace[29443466] transaction","detail":"{read_only:false; response_revision:14765; number_of_response:1; }","duration":"144.443089ms","start":"2026-05-27T20:13:43.308271Z","end":"2026-05-27T20:13:43.452714Z","steps":["trace[29443466] 'process raft request' (duration: 144.229783ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:13:43.453176Z","caller":"traceutil/trace.go:171","msg":"trace[2000695431] linearizableReadLoop","detail":"{readStateIndex:15284; appliedIndex:15280; }","duration":"144.183202ms","start":"2026-05-27T20:13:43.308519Z","end":"2026-05-27T20:13:43.452703Z","steps":["trace[2000695431] 'read index received' (duration: 138.65823ms)","trace[2000695431] 'applied index is now lower than readState.Index' (duration: 5.522492ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:13:43.453873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.332883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:66 size:630799"} {"level":"info","ts":"2026-05-27T20:13:43.453928Z","caller":"traceutil/trace.go:171","msg":"trace[1787715659] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:66; response_revision:14765; }","duration":"145.403085ms","start":"2026-05-27T20:13:43.308512Z","end":"2026-05-27T20:13:43.453915Z","steps":["trace[1787715659] 'agreement among raft nodes before linearized reading' (duration: 144.630834ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:14:08.01424Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12779} {"level":"info","ts":"2026-05-27T20:14:08.093749Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12779,"took":"77.173991ms","hash":1514924032,"current-db-size-bytes":57221120,"current-db-size":"57 MB","current-db-size-in-use-bytes":28786688,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-27T20:14:08.093812Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1514924032,"revision":12779,"compact-revision":10522} {"level":"info","ts":"2026-05-27T20:14:59.973566Z","caller":"traceutil/trace.go:171","msg":"trace[1586066759] linearizableReadLoop","detail":"{readStateIndex:16062; appliedIndex:16061; }","duration":"114.779531ms","start":"2026-05-27T20:14:59.858754Z","end":"2026-05-27T20:14:59.973534Z","steps":["trace[1586066759] 'read index received' (duration: 114.524784ms)","trace[1586066759] 'applied index is now lower than readState.Index' (duration: 253.677µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:14:59.973998Z","caller":"traceutil/trace.go:171","msg":"trace[1699801489] transaction","detail":"{read_only:false; response_revision:15520; number_of_response:1; }","duration":"117.136636ms","start":"2026-05-27T20:14:59.856751Z","end":"2026-05-27T20:14:59.973887Z","steps":["trace[1699801489] 'process raft request' (duration: 116.602701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:14:59.974297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.514621ms","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-05-27T20:14:59.974348Z","caller":"traceutil/trace.go:171","msg":"trace[1385848578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15520; }","duration":"115.582333ms","start":"2026-05-27T20:14:59.85875Z","end":"2026-05-27T20:14:59.974332Z","steps":["trace[1385848578] 'agreement among raft nodes before linearized reading' (duration: 115.415328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:14:59.974738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.632534ms","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-05-27T20:14:59.974844Z","caller":"traceutil/trace.go:171","msg":"trace[781142273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15520; }","duration":"115.783078ms","start":"2026-05-27T20:14:59.85904Z","end":"2026-05-27T20:14:59.974823Z","steps":["trace[781142273] 'agreement among raft nodes before linearized reading' (duration: 115.524641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:14:59.975114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.786939ms","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-05-27T20:14:59.975159Z","caller":"traceutil/trace.go:171","msg":"trace[516176440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15520; }","duration":"115.846861ms","start":"2026-05-27T20:14:59.859303Z","end":"2026-05-27T20:14:59.97515Z","steps":["trace[516176440] 'agreement among raft nodes before linearized reading' (duration: 115.738037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:14:59.975215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.453226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-05-27T20:14:59.975277Z","caller":"traceutil/trace.go:171","msg":"trace[1914591322] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15520; }","duration":"113.525847ms","start":"2026-05-27T20:14:59.861729Z","end":"2026-05-27T20:14:59.975255Z","steps":["trace[1914591322] 'agreement among raft nodes before linearized reading' (duration: 113.412184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:14:59.975077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.586425ms","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-05-27T20:14:59.975343Z","caller":"traceutil/trace.go:171","msg":"trace[228445606] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15520; }","duration":"114.882714ms","start":"2026-05-27T20:14:59.860444Z","end":"2026-05-27T20:14:59.975327Z","steps":["trace[228445606] 'agreement among raft nodes before linearized reading' (duration: 114.485582ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:15:00.151343Z","caller":"traceutil/trace.go:171","msg":"trace[781561912] transaction","detail":"{read_only:false; response_revision:15522; number_of_response:1; }","duration":"171.215831ms","start":"2026-05-27T20:14:59.980102Z","end":"2026-05-27T20:15:00.151318Z","steps":["trace[781561912] 'process raft request' (duration: 170.979544ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:15:00.151916Z","caller":"traceutil/trace.go:171","msg":"trace[1019383408] transaction","detail":"{read_only:false; response_revision:15523; number_of_response:1; }","duration":"171.511799ms","start":"2026-05-27T20:14:59.980346Z","end":"2026-05-27T20:15:00.151858Z","steps":["trace[1019383408] 'process raft request' (duration: 170.894292ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:15:00.152164Z","caller":"traceutil/trace.go:171","msg":"trace[45134716] transaction","detail":"{read_only:false; response_revision:15524; number_of_response:1; }","duration":"169.236337ms","start":"2026-05-27T20:14:59.982874Z","end":"2026-05-27T20:15:00.152111Z","steps":["trace[45134716] 'process raft request' (duration: 168.743083ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:19:08.03986Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14927} {"level":"info","ts":"2026-05-27T20:19:08.154127Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14927,"took":"111.103437ms","hash":2040488463,"current-db-size-bytes":57221120,"current-db-size":"57 MB","current-db-size-in-use-bytes":30556160,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-27T20:19:08.154197Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2040488463,"revision":14927,"compact-revision":12779} {"level":"info","ts":"2026-05-27T20:20:52.332621Z","caller":"traceutil/trace.go:171","msg":"trace[124986116] linearizableReadLoop","detail":"{readStateIndex:18753; appliedIndex:18750; }","duration":"271.555756ms","start":"2026-05-27T20:20:52.061044Z","end":"2026-05-27T20:20:52.332599Z","steps":["trace[124986116] 'read index received' (duration: 12.144904ms)","trace[124986116] 'applied index is now lower than readState.Index' (duration: 259.409692ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:20:52.332741Z","caller":"traceutil/trace.go:171","msg":"trace[629211135] transaction","detail":"{read_only:false; response_revision:18125; number_of_response:1; }","duration":"298.802772ms","start":"2026-05-27T20:20:52.033927Z","end":"2026-05-27T20:20:52.33273Z","steps":["trace[629211135] 'process raft request' (duration: 298.448903ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:20:52.332891Z","caller":"traceutil/trace.go:171","msg":"trace[297454881] transaction","detail":"{read_only:false; response_revision:18126; number_of_response:1; }","duration":"288.853449ms","start":"2026-05-27T20:20:52.044028Z","end":"2026-05-27T20:20:52.332881Z","steps":["trace[297454881] 'process raft request' (duration: 288.52583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:20:52.333064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.015299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-27T20:20:52.333095Z","caller":"traceutil/trace.go:171","msg":"trace[128892225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18126; }","duration":"272.119121ms","start":"2026-05-27T20:20:52.060966Z","end":"2026-05-27T20:20:52.333085Z","steps":["trace[128892225] 'agreement among raft nodes before linearized reading' (duration: 271.984047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:20:52.333264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.199625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-27T20:20:52.333345Z","caller":"traceutil/trace.go:171","msg":"trace[1902775592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18126; }","duration":"252.316018ms","start":"2026-05-27T20:20:52.081011Z","end":"2026-05-27T20:20:52.333327Z","steps":["trace[1902775592] 'agreement among raft nodes before linearized reading' (duration: 252.146114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:20:52.333546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.930351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-27T20:20:52.333588Z","caller":"traceutil/trace.go:171","msg":"trace[1761448781] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18126; }","duration":"246.988063ms","start":"2026-05-27T20:20:52.086587Z","end":"2026-05-27T20:20:52.333575Z","steps":["trace[1761448781] 'agreement among raft nodes before linearized reading' (duration: 246.899051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:20:52.669074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.823717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:20:52.66916Z","caller":"traceutil/trace.go:171","msg":"trace[1712142887] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:18129; }","duration":"126.966631ms","start":"2026-05-27T20:20:52.542176Z","end":"2026-05-27T20:20:52.669142Z","steps":["trace[1712142887] 'count revisions from in-memory index tree' (duration: 126.677564ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:20:53.188252Z","caller":"traceutil/trace.go:171","msg":"trace[393314737] linearizableReadLoop","detail":"{readStateIndex:18757; appliedIndex:18756; }","duration":"444.744085ms","start":"2026-05-27T20:20:52.743481Z","end":"2026-05-27T20:20:53.188225Z","steps":["trace[393314737] 'read index received' (duration: 444.25148ms)","trace[393314737] 'applied index is now lower than readState.Index' (duration: 491.755µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:20:53.188441Z","caller":"traceutil/trace.go:171","msg":"trace[1409888644] transaction","detail":"{read_only:false; response_revision:18130; number_of_response:1; }","duration":"481.868922ms","start":"2026-05-27T20:20:52.706527Z","end":"2026-05-27T20:20:53.188396Z","steps":["trace[1409888644] 'process raft request' (duration: 481.4194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:20:53.188629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.124435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-05-27T20:20:53.188724Z","caller":"traceutil/trace.go:171","msg":"trace[1232574891] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:18130; }","duration":"445.227657ms","start":"2026-05-27T20:20:52.743469Z","end":"2026-05-27T20:20:53.188696Z","steps":["trace[1232574891] 'agreement among raft nodes before linearized reading' (duration: 444.96105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:20:53.188785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:20:52.743459Z","time spent":"445.316071ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1959,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-05-27T20:20:53.188918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:20:52.706462Z","time spent":"482.177721ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20988,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:23:03.117027Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8a0c9a032e61a05a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-27T20:23:03.128493Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-27T20:23:03.12878Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-27T20:24:08.048833Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17420} {"level":"info","ts":"2026-05-27T20:24:08.169055Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17420,"took":"115.407415ms","hash":3906047850,"current-db-size-bytes":57221120,"current-db-size":"57 MB","current-db-size-in-use-bytes":34897920,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-27T20:24:08.169116Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3906047850,"revision":17420,"compact-revision":14927} {"level":"info","ts":"2026-05-27T20:26:23.343712Z","caller":"traceutil/trace.go:171","msg":"trace[1577664918] transaction","detail":"{read_only:false; response_revision:20736; number_of_response:1; }","duration":"130.754933ms","start":"2026-05-27T20:26:23.212919Z","end":"2026-05-27T20:26:23.343674Z","steps":["trace[1577664918] 'process raft request' (duration: 130.194698ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:26:45.718981Z","caller":"traceutil/trace.go:171","msg":"trace[1130927231] transaction","detail":"{read_only:false; response_revision:20852; number_of_response:1; }","duration":"211.70841ms","start":"2026-05-27T20:26:45.507246Z","end":"2026-05-27T20:26:45.718955Z","steps":["trace[1130927231] 'process raft request' (duration: 211.455884ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:26:45.862162Z","caller":"traceutil/trace.go:171","msg":"trace[713022756] transaction","detail":"{read_only:false; response_revision:20853; number_of_response:1; }","duration":"292.518655ms","start":"2026-05-27T20:26:45.569602Z","end":"2026-05-27T20:26:45.86212Z","steps":["trace[713022756] 'process raft request' (duration: 260.968159ms)","trace[713022756] 'compare' (duration: 31.263519ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:26:45.862388Z","caller":"traceutil/trace.go:171","msg":"trace[597096051] transaction","detail":"{read_only:false; response_revision:20854; number_of_response:1; }","duration":"210.414456ms","start":"2026-05-27T20:26:45.651953Z","end":"2026-05-27T20:26:45.862368Z","steps":["trace[597096051] 'process raft request' (duration: 210.113308ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:26:45.862554Z","caller":"traceutil/trace.go:171","msg":"trace[131170222] transaction","detail":"{read_only:false; response_revision:20855; number_of_response:1; }","duration":"206.299794ms","start":"2026-05-27T20:26:45.656241Z","end":"2026-05-27T20:26:45.86254Z","steps":["trace[131170222] 'process raft request' (duration: 206.040997ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:26:45.862525Z","caller":"traceutil/trace.go:171","msg":"trace[1119953993] linearizableReadLoop","detail":"{readStateIndex:21571; appliedIndex:21569; }","duration":"280.678174ms","start":"2026-05-27T20:26:45.581808Z","end":"2026-05-27T20:26:45.862486Z","steps":["trace[1119953993] 'read index received' (duration: 136.9472ms)","trace[1119953993] 'applied index is now lower than readState.Index' (duration: 143.729224ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:26:45.862786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.695444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-27T20:26:45.862856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.339908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:26:45.862933Z","caller":"traceutil/trace.go:171","msg":"trace[1218326516] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:20855; }","duration":"163.469931ms","start":"2026-05-27T20:26:45.699446Z","end":"2026-05-27T20:26:45.862916Z","steps":["trace[1218326516] 'agreement among raft nodes before linearized reading' (duration: 163.336867ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:26:45.862929Z","caller":"traceutil/trace.go:171","msg":"trace[1337978148] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:20855; }","duration":"167.86052ms","start":"2026-05-27T20:26:45.69501Z","end":"2026-05-27T20:26:45.862871Z","steps":["trace[1337978148] 'agreement among raft nodes before linearized reading' (duration: 167.695994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:26:45.863237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.405904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:27 size:240060"} {"level":"info","ts":"2026-05-27T20:26:45.863287Z","caller":"traceutil/trace.go:171","msg":"trace[755809626] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:27; response_revision:20855; }","duration":"281.531527ms","start":"2026-05-27T20:26:45.58174Z","end":"2026-05-27T20:26:45.863271Z","steps":["trace[755809626] 'agreement among raft nodes before linearized reading' (duration: 280.945641ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:26:46.102054Z","caller":"traceutil/trace.go:171","msg":"trace[1037668577] transaction","detail":"{read_only:false; response_revision:20856; number_of_response:1; }","duration":"213.219371ms","start":"2026-05-27T20:26:45.888768Z","end":"2026-05-27T20:26:46.101987Z","steps":["trace[1037668577] 'process raft request' (duration: 113.655846ms)","trace[1037668577] 'compare' (duration: 99.317139ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:26:46.102729Z","caller":"traceutil/trace.go:171","msg":"trace[1633930079] transaction","detail":"{read_only:false; response_revision:20857; number_of_response:1; }","duration":"212.652366ms","start":"2026-05-27T20:26:45.890065Z","end":"2026-05-27T20:26:46.102718Z","steps":["trace[1633930079] 'process raft request' (duration: 212.596414ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:08.056331Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19740} {"level":"info","ts":"2026-05-27T20:29:08.182931Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19740,"took":"121.503569ms","hash":2145767924,"current-db-size-bytes":57221120,"current-db-size":"57 MB","current-db-size-in-use-bytes":29614080,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-27T20:29:08.183015Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2145767924,"revision":19740,"compact-revision":17420} {"level":"info","ts":"2026-05-27T20:34:08.063607Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21974} {"level":"info","ts":"2026-05-27T20:34:08.19113Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21974,"took":"118.728306ms","hash":370652012,"current-db-size-bytes":57221120,"current-db-size":"57 MB","current-db-size-in-use-bytes":43089920,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-05-27T20:34:08.191206Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":370652012,"revision":21974,"compact-revision":19740} {"level":"info","ts":"2026-05-27T20:34:26.688357Z","caller":"traceutil/trace.go:171","msg":"trace[2119588661] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"152.28019ms","start":"2026-05-27T20:34:26.536053Z","end":"2026-05-27T20:34:26.688333Z","steps":["trace[2119588661] 'process raft request' (duration: 152.000503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:27.00508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.442498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T20:34:27.005284Z","caller":"traceutil/trace.go:171","msg":"trace[1565023519] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"312.596308ms","start":"2026-05-27T20:34:26.692661Z","end":"2026-05-27T20:34:27.005257Z","steps":["trace[1565023519] 'process raft request' (duration: 62.867983ms)","trace[1565023519] 'compare' (duration: 249.006896ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:34:27.005316Z","caller":"traceutil/trace.go:171","msg":"trace[63959897] linearizableReadLoop","detail":"{readStateIndex:26370; appliedIndex:26369; }","duration":"312.419204ms","start":"2026-05-27T20:34:26.692868Z","end":"2026-05-27T20:34:27.005287Z","steps":["trace[63959897] 'read index received' (duration: 29.629928ms)","trace[63959897] 'applied index is now lower than readState.Index' (duration: 282.786766ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:27.005423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:26.692645Z","time spent":"312.713352ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:34:27.165754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.538715ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-05-27T20:34:27.166013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.118973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-27T20:34:27.166095Z","caller":"traceutil/trace.go:171","msg":"trace[1554108112] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25518; }","duration":"473.215205ms","start":"2026-05-27T20:34:26.692853Z","end":"2026-05-27T20:34:27.166068Z","steps":["trace[1554108112] 'agreement among raft nodes before linearized reading' (duration: 312.600509ms)","trace[1554108112] 'range keys from in-memory index tree' (duration: 160.391271ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:27.166155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:26.692847Z","time spent":"473.297607ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-27T20:34:27.166254Z","caller":"traceutil/trace.go:171","msg":"trace[736985281] linearizableReadLoop","detail":"{readStateIndex:26371; appliedIndex:26370; }","duration":"160.768511ms","start":"2026-05-27T20:34:27.005405Z","end":"2026-05-27T20:34:27.166173Z","steps":["trace[736985281] 'read index received' (duration: 56.962µs)","trace[736985281] 'applied index is now lower than readState.Index' (duration: 160.709989ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:27.166836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.577585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:34:27.16689Z","caller":"traceutil/trace.go:171","msg":"trace[845073585] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25518; }","duration":"342.693708ms","start":"2026-05-27T20:34:26.824181Z","end":"2026-05-27T20:34:27.166875Z","steps":["trace[845073585] 'agreement among raft nodes before linearized reading' (duration: 342.124233ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:27.166903Z","caller":"traceutil/trace.go:171","msg":"trace[1099473543] transaction","detail":"{read_only:false; response_revision:25519; number_of_response:1; }","duration":"142.826162ms","start":"2026-05-27T20:34:27.024059Z","end":"2026-05-27T20:34:27.166885Z","steps":["trace[1099473543] 'process raft request' (duration: 141.824604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:27.166947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:26.824156Z","time spent":"342.779022ms","remote":"127.0.0.1:59768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"warn","ts":"2026-05-27T20:34:27.167049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.168095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274828"} {"level":"info","ts":"2026-05-27T20:34:27.167157Z","caller":"traceutil/trace.go:171","msg":"trace[1378460033] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:25518; }","duration":"291.298478ms","start":"2026-05-27T20:34:26.87584Z","end":"2026-05-27T20:34:27.167139Z","steps":["trace[1378460033] 'agreement among raft nodes before linearized reading' (duration: 290.697741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:27.2987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.152757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-27T20:34:27.298776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.855325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-27T20:34:27.298804Z","caller":"traceutil/trace.go:171","msg":"trace[426602709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25519; }","duration":"428.293581ms","start":"2026-05-27T20:34:26.870481Z","end":"2026-05-27T20:34:27.298775Z","steps":["trace[426602709] 'agreement among raft nodes before linearized reading' (duration: 296.328234ms)","trace[426602709] 'range keys from in-memory index tree' (duration: 131.714499ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:27.298782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.054125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-27T20:34:27.29885Z","caller":"traceutil/trace.go:171","msg":"trace[1690843872] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25519; }","duration":"436.951628ms","start":"2026-05-27T20:34:26.861874Z","end":"2026-05-27T20:34:27.298826Z","steps":["trace[1690843872] 'agreement among raft nodes before linearized reading' (duration: 304.97299ms)","trace[1690843872] 'range keys from in-memory index tree' (duration: 131.69338ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:27.298853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:26.870464Z","time spent":"428.378994ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T20:34:27.298891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:26.861861Z","time spent":"437.021449ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-27T20:34:27.298887Z","caller":"traceutil/trace.go:171","msg":"trace[1207465335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25519; }","duration":"428.21988ms","start":"2026-05-27T20:34:26.870646Z","end":"2026-05-27T20:34:27.298866Z","steps":["trace[1207465335] 'agreement among raft nodes before linearized reading' (duration: 296.185151ms)","trace[1207465335] 'range keys from in-memory index tree' (duration: 131.731451ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:27.298925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.281527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18420"} {"level":"info","ts":"2026-05-27T20:34:27.29901Z","caller":"traceutil/trace.go:171","msg":"trace[1849408857] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25519; }","duration":"131.398671ms","start":"2026-05-27T20:34:27.167587Z","end":"2026-05-27T20:34:27.298986Z","steps":["trace[1849408857] 'range keys from in-memory index tree' (duration: 130.929099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:27.298975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:26.870615Z","time spent":"428.344673ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T20:34:27.618943Z","caller":"traceutil/trace.go:171","msg":"trace[1805772579] transaction","detail":"{read_only:false; response_revision:25521; number_of_response:1; }","duration":"314.1548ms","start":"2026-05-27T20:34:27.304732Z","end":"2026-05-27T20:34:27.618887Z","steps":["trace[1805772579] 'process raft request' (duration: 258.335329ms)","trace[1805772579] 'compare' (duration: 55.199895ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:27.619332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:27.304717Z","time spent":"314.461699ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:34:27.61958Z","caller":"traceutil/trace.go:171","msg":"trace[407290229] linearizableReadLoop","detail":"{readStateIndex:26375; appliedIndex:26372; }","duration":"257.704783ms","start":"2026-05-27T20:34:27.361857Z","end":"2026-05-27T20:34:27.619561Z","steps":["trace[407290229] 'read index received' (duration: 13.59064ms)","trace[407290229] 'applied index is now lower than readState.Index' (duration: 244.112353ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:34:27.619515Z","caller":"traceutil/trace.go:171","msg":"trace[308478793] transaction","detail":"{read_only:false; response_revision:25522; number_of_response:1; }","duration":"308.572629ms","start":"2026-05-27T20:34:27.310561Z","end":"2026-05-27T20:34:27.619134Z","steps":["trace[308478793] 'process raft request' (duration: 308.023364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:27.619702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.824236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:34:27.619847Z","caller":"traceutil/trace.go:171","msg":"trace[2138579247] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25522; }","duration":"257.97858ms","start":"2026-05-27T20:34:27.361809Z","end":"2026-05-27T20:34:27.619787Z","steps":["trace[2138579247] 'agreement among raft nodes before linearized reading' (duration: 257.822145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:27.619885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:27.310553Z","time spent":"309.216696ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:34:27.620617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.647872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-27T20:34:27.620671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.775296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:34:27.620741Z","caller":"traceutil/trace.go:171","msg":"trace[2024129863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25524; }","duration":"140.87357ms","start":"2026-05-27T20:34:27.479844Z","end":"2026-05-27T20:34:27.620718Z","steps":["trace[2024129863] 'agreement among raft nodes before linearized reading' (duration: 140.770626ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:27.620733Z","caller":"traceutil/trace.go:171","msg":"trace[304459535] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:25524; }","duration":"162.814307ms","start":"2026-05-27T20:34:27.457893Z","end":"2026-05-27T20:34:27.620707Z","steps":["trace[304459535] 'agreement among raft nodes before linearized reading' (duration: 162.624921ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:27.795917Z","caller":"traceutil/trace.go:171","msg":"trace[399910315] transaction","detail":"{read_only:false; response_revision:25525; number_of_response:1; }","duration":"139.43265ms","start":"2026-05-27T20:34:27.656434Z","end":"2026-05-27T20:34:27.795867Z","steps":["trace[399910315] 'process raft request' (duration: 101.104935ms)","trace[399910315] 'compare' (duration: 38.091539ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:31.670783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.108589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T20:34:31.670879Z","caller":"traceutil/trace.go:171","msg":"trace[1804594380] linearizableReadLoop","detail":"{readStateIndex:26404; appliedIndex:26403; }","duration":"125.583582ms","start":"2026-05-27T20:34:31.545281Z","end":"2026-05-27T20:34:31.670864Z","steps":["trace[1804594380] 'read index received' (duration: 52.611µs)","trace[1804594380] 'applied index is now lower than readState.Index' (duration: 125.529421ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:31.671101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.820278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418324"} {"level":"info","ts":"2026-05-27T20:34:31.671131Z","caller":"traceutil/trace.go:171","msg":"trace[759592507] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25550; }","duration":"125.89232ms","start":"2026-05-27T20:34:31.545227Z","end":"2026-05-27T20:34:31.671119Z","steps":["trace[759592507] 'agreement among raft nodes before linearized reading' (duration: 125.697395ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:31.671154Z","caller":"traceutil/trace.go:171","msg":"trace[2083300104] transaction","detail":"{read_only:false; response_revision:25550; number_of_response:1; }","duration":"236.544126ms","start":"2026-05-27T20:34:31.43434Z","end":"2026-05-27T20:34:31.670884Z","steps":["trace[2083300104] 'process raft request' (duration: 75.238171ms)","trace[2083300104] 'compare' (duration: 161.010277ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:31.994652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.594076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T20:34:31.994861Z","caller":"traceutil/trace.go:171","msg":"trace[792177965] linearizableReadLoop","detail":"{readStateIndex:26408; appliedIndex:26406; }","duration":"275.445546ms","start":"2026-05-27T20:34:31.7194Z","end":"2026-05-27T20:34:31.994845Z","steps":["trace[792177965] 'read index received' (duration: 92.551822ms)","trace[792177965] 'applied index is now lower than readState.Index' (duration: 182.892734ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:31.994932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.537359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:34:31.994913Z","caller":"traceutil/trace.go:171","msg":"trace[1089874465] transaction","detail":"{read_only:false; response_revision:25554; number_of_response:1; }","duration":"282.056276ms","start":"2026-05-27T20:34:31.712819Z","end":"2026-05-27T20:34:31.994875Z","steps":["trace[1089874465] 'process raft request' (duration: 281.932853ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:31.995035Z","caller":"traceutil/trace.go:171","msg":"trace[1209564334] transaction","detail":"{read_only:false; response_revision:25553; number_of_response:1; }","duration":"282.234562ms","start":"2026-05-27T20:34:31.712794Z","end":"2026-05-27T20:34:31.995029Z","steps":["trace[1209564334] 'process raft request' (duration: 99.174223ms)","trace[1209564334] 'compare' (duration: 182.493933ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:34:31.994955Z","caller":"traceutil/trace.go:171","msg":"trace[282989046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25556; }","duration":"275.594929ms","start":"2026-05-27T20:34:31.719353Z","end":"2026-05-27T20:34:31.994948Z","steps":["trace[282989046] 'agreement among raft nodes before linearized reading' (duration: 275.536448ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:31.994986Z","caller":"traceutil/trace.go:171","msg":"trace[872011798] transaction","detail":"{read_only:false; response_revision:25555; number_of_response:1; }","duration":"187.927411ms","start":"2026-05-27T20:34:31.807049Z","end":"2026-05-27T20:34:31.994976Z","steps":["trace[872011798] 'process raft request' (duration: 187.738116ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:31.995009Z","caller":"traceutil/trace.go:171","msg":"trace[575829170] transaction","detail":"{read_only:false; response_revision:25556; number_of_response:1; }","duration":"185.083214ms","start":"2026-05-27T20:34:31.809921Z","end":"2026-05-27T20:34:31.995004Z","steps":["trace[575829170] 'process raft request' (duration: 184.894589ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:31.99952Z","caller":"traceutil/trace.go:171","msg":"trace[609037742] transaction","detail":"{read_only:false; response_revision:25558; number_of_response:1; }","duration":"184.473147ms","start":"2026-05-27T20:34:31.815023Z","end":"2026-05-27T20:34:31.999496Z","steps":["trace[609037742] 'process raft request' (duration: 184.395825ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:31.999713Z","caller":"traceutil/trace.go:171","msg":"trace[617106144] transaction","detail":"{read_only:false; response_revision:25559; number_of_response:1; }","duration":"139.770499ms","start":"2026-05-27T20:34:31.859924Z","end":"2026-05-27T20:34:31.999695Z","steps":["trace[617106144] 'process raft request' (duration: 139.532782ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:31.999845Z","caller":"traceutil/trace.go:171","msg":"trace[602337770] transaction","detail":"{read_only:false; response_revision:25557; number_of_response:1; }","duration":"187.650773ms","start":"2026-05-27T20:34:31.812182Z","end":"2026-05-27T20:34:31.999833Z","steps":["trace[602337770] 'process raft request' (duration: 187.099349ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:32.291883Z","caller":"traceutil/trace.go:171","msg":"trace[222668743] transaction","detail":"{read_only:false; response_revision:25560; number_of_response:1; }","duration":"215.405811ms","start":"2026-05-27T20:34:32.076456Z","end":"2026-05-27T20:34:32.291862Z","steps":["trace[222668743] 'process raft request' (duration: 214.213618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:32.623152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.529246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:34:32.623246Z","caller":"traceutil/trace.go:171","msg":"trace[1502603387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25560; }","duration":"145.67614ms","start":"2026-05-27T20:34:32.477546Z","end":"2026-05-27T20:34:32.623222Z","steps":["trace[1502603387] 'range keys from in-memory index tree' (duration: 145.437764ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:38.265148Z","caller":"traceutil/trace.go:171","msg":"trace[1281437153] linearizableReadLoop","detail":"{readStateIndex:26454; appliedIndex:26450; }","duration":"232.425754ms","start":"2026-05-27T20:34:38.032689Z","end":"2026-05-27T20:34:38.265114Z","steps":["trace[1281437153] 'read index received' (duration: 79.147896ms)","trace[1281437153] 'applied index is now lower than readState.Index' (duration: 153.276338ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:34:38.265441Z","caller":"traceutil/trace.go:171","msg":"trace[98723918] transaction","detail":"{read_only:false; response_revision:25600; number_of_response:1; }","duration":"227.15522ms","start":"2026-05-27T20:34:38.038228Z","end":"2026-05-27T20:34:38.265383Z","steps":["trace[98723918] 'process raft request' (duration: 226.844102ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:38.265481Z","caller":"traceutil/trace.go:171","msg":"trace[1455398822] transaction","detail":"{read_only:false; response_revision:25598; number_of_response:1; }","duration":"241.988864ms","start":"2026-05-27T20:34:38.023458Z","end":"2026-05-27T20:34:38.265447Z","steps":["trace[1455398822] 'process raft request' (duration: 241.523431ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:38.265631Z","caller":"traceutil/trace.go:171","msg":"trace[810183660] transaction","detail":"{read_only:false; response_revision:25597; number_of_response:1; }","duration":"242.545169ms","start":"2026-05-27T20:34:38.023067Z","end":"2026-05-27T20:34:38.265612Z","steps":["trace[810183660] 'process raft request' (duration: 241.755868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:38.265592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.325554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-27T20:34:38.265655Z","caller":"traceutil/trace.go:171","msg":"trace[1250263797] transaction","detail":"{read_only:false; response_revision:25599; number_of_response:1; }","duration":"240.685179ms","start":"2026-05-27T20:34:38.024953Z","end":"2026-05-27T20:34:38.265638Z","steps":["trace[1250263797] 'process raft request' (duration: 240.075672ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:38.265722Z","caller":"traceutil/trace.go:171","msg":"trace[2023203461] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25600; }","duration":"231.468967ms","start":"2026-05-27T20:34:38.034215Z","end":"2026-05-27T20:34:38.265684Z","steps":["trace[2023203461] 'agreement among raft nodes before linearized reading' (duration: 231.19243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:38.265896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.168324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-27T20:34:38.266001Z","caller":"traceutil/trace.go:171","msg":"trace[82742484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25600; }","duration":"233.302689ms","start":"2026-05-27T20:34:38.032676Z","end":"2026-05-27T20:34:38.265979Z","steps":["trace[82742484] 'agreement among raft nodes before linearized reading' (duration: 232.907187ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:38.460779Z","caller":"traceutil/trace.go:171","msg":"trace[1164674594] linearizableReadLoop","detail":"{readStateIndex:26457; appliedIndex:26456; }","duration":"119.351262ms","start":"2026-05-27T20:34:38.34141Z","end":"2026-05-27T20:34:38.460762Z","steps":["trace[1164674594] 'read index received' (duration: 75.451406ms)","trace[1164674594] 'applied index is now lower than readState.Index' (duration: 43.899196ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:38.461006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.586409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-27T20:34:38.461032Z","caller":"traceutil/trace.go:171","msg":"trace[133418641] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:25602; }","duration":"119.673451ms","start":"2026-05-27T20:34:38.341352Z","end":"2026-05-27T20:34:38.461026Z","steps":["trace[133418641] 'agreement among raft nodes before linearized reading' (duration: 119.550968ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:38.461099Z","caller":"traceutil/trace.go:171","msg":"trace[554399154] transaction","detail":"{read_only:false; response_revision:25602; number_of_response:1; }","duration":"183.436849ms","start":"2026-05-27T20:34:38.277629Z","end":"2026-05-27T20:34:38.461066Z","steps":["trace[554399154] 'process raft request' (duration: 139.318326ms)","trace[554399154] 'compare' (duration: 43.543187ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:34:38.914291Z","caller":"traceutil/trace.go:171","msg":"trace[537660783] linearizableReadLoop","detail":"{readStateIndex:26458; appliedIndex:26457; }","duration":"127.806403ms","start":"2026-05-27T20:34:38.78646Z","end":"2026-05-27T20:34:38.914266Z","steps":["trace[537660783] 'read index received' (duration: 127.537816ms)","trace[537660783] 'applied index is now lower than readState.Index' (duration: 267.477µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:38.914544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.875192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-27T20:34:38.914548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.06044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-05-27T20:34:38.914588Z","caller":"traceutil/trace.go:171","msg":"trace[1520413407] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25603; }","duration":"110.992665ms","start":"2026-05-27T20:34:38.803584Z","end":"2026-05-27T20:34:38.914576Z","steps":["trace[1520413407] 'agreement among raft nodes before linearized reading' (duration: 110.829491ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:38.914519Z","caller":"traceutil/trace.go:171","msg":"trace[145016364] transaction","detail":"{read_only:false; response_revision:25603; number_of_response:1; }","duration":"128.820181ms","start":"2026-05-27T20:34:38.785658Z","end":"2026-05-27T20:34:38.914478Z","steps":["trace[145016364] 'process raft request' (duration: 128.43981ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:38.914623Z","caller":"traceutil/trace.go:171","msg":"trace[2087865518] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:25603; }","duration":"128.176553ms","start":"2026-05-27T20:34:38.786426Z","end":"2026-05-27T20:34:38.914603Z","steps":["trace[2087865518] 'agreement among raft nodes before linearized reading' (duration: 127.962437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:39.063241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.352054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T20:34:39.063382Z","caller":"traceutil/trace.go:171","msg":"trace[1044479770] transaction","detail":"{read_only:false; response_revision:25604; number_of_response:1; }","duration":"142.923294ms","start":"2026-05-27T20:34:38.920434Z","end":"2026-05-27T20:34:39.063358Z","steps":["trace[1044479770] 'compare' (duration: 132.858571ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:39.229017Z","caller":"traceutil/trace.go:171","msg":"trace[721309813] linearizableReadLoop","detail":"{readStateIndex:26460; appliedIndex:26459; }","duration":"122.184969ms","start":"2026-05-27T20:34:39.106787Z","end":"2026-05-27T20:34:39.228972Z","steps":["trace[721309813] 'read index received' (duration: 109.779202ms)","trace[721309813] 'applied index is now lower than readState.Index' (duration: 12.403717ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:39.229277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.439936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:34:39.229329Z","caller":"traceutil/trace.go:171","msg":"trace[1144673882] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:25605; }","duration":"122.58592ms","start":"2026-05-27T20:34:39.106728Z","end":"2026-05-27T20:34:39.229314Z","steps":["trace[1144673882] 'agreement among raft nodes before linearized reading' (duration: 122.406605ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:39.229348Z","caller":"traceutil/trace.go:171","msg":"trace[394325018] transaction","detail":"{read_only:false; response_revision:25605; number_of_response:1; }","duration":"127.803502ms","start":"2026-05-27T20:34:39.101479Z","end":"2026-05-27T20:34:39.229283Z","steps":["trace[394325018] 'process raft request' (duration: 115.103706ms)","trace[394325018] 'compare' (duration: 12.081719ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:34:39.651903Z","caller":"traceutil/trace.go:171","msg":"trace[2103599935] transaction","detail":"{read_only:false; response_revision:25606; number_of_response:1; }","duration":"187.715595ms","start":"2026-05-27T20:34:39.464158Z","end":"2026-05-27T20:34:39.651874Z","steps":["trace[2103599935] 'process raft request' (duration: 187.489159ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:39.897021Z","caller":"traceutil/trace.go:171","msg":"trace[334340667] transaction","detail":"{read_only:false; response_revision:25607; number_of_response:1; }","duration":"149.67933ms","start":"2026-05-27T20:34:39.747262Z","end":"2026-05-27T20:34:39.896942Z","steps":["trace[334340667] 'process raft request' (duration: 138.601058ms)","trace[334340667] 'compare' (duration: 10.62855ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:34:40.07478Z","caller":"traceutil/trace.go:171","msg":"trace[361896103] linearizableReadLoop","detail":"{readStateIndex:26464; appliedIndex:26462; }","duration":"121.83589ms","start":"2026-05-27T20:34:39.952917Z","end":"2026-05-27T20:34:40.074753Z","steps":["trace[361896103] 'read index received' (duration: 84.358699ms)","trace[361896103] 'applied index is now lower than readState.Index' (duration: 37.475901ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:34:40.075201Z","caller":"traceutil/trace.go:171","msg":"trace[2126174421] transaction","detail":"{read_only:false; response_revision:25608; number_of_response:1; }","duration":"327.102613ms","start":"2026-05-27T20:34:39.74808Z","end":"2026-05-27T20:34:40.075183Z","steps":["trace[2126174421] 'process raft request' (duration: 289.294273ms)","trace[2126174421] 'compare' (duration: 36.966238ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:34:40.075339Z","caller":"traceutil/trace.go:171","msg":"trace[1109397012] transaction","detail":"{read_only:false; response_revision:25609; number_of_response:1; }","duration":"230.699566ms","start":"2026-05-27T20:34:39.844592Z","end":"2026-05-27T20:34:40.075291Z","steps":["trace[1109397012] 'process raft request' (duration: 230.09615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:40.075514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.5907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-27T20:34:40.075574Z","caller":"traceutil/trace.go:171","msg":"trace[258529585] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:25609; }","duration":"122.691682ms","start":"2026-05-27T20:34:39.952866Z","end":"2026-05-27T20:34:40.075558Z","steps":["trace[258529585] 'agreement among raft nodes before linearized reading' (duration: 122.59259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:40.075365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:39.748054Z","time spent":"327.198236ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:34:40.327837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.738544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T20:34:40.327982Z","caller":"traceutil/trace.go:171","msg":"trace[1093225607] transaction","detail":"{read_only:false; response_revision:25610; number_of_response:1; }","duration":"210.873927ms","start":"2026-05-27T20:34:40.117077Z","end":"2026-05-27T20:34:40.327951Z","steps":["trace[1093225607] 'process raft request' (duration: 76.879426ms)","trace[1093225607] 'compare' (duration: 132.921161ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:34:40.328173Z","caller":"traceutil/trace.go:171","msg":"trace[1520900973] linearizableReadLoop","detail":"{readStateIndex:26465; appliedIndex:26464; }","duration":"167.32945ms","start":"2026-05-27T20:34:40.160833Z","end":"2026-05-27T20:34:40.328162Z","steps":["trace[1520900973] 'read index received' (duration: 33.137093ms)","trace[1520900973] 'applied index is now lower than readState.Index' (duration: 134.190787ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:40.328296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.441903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:34:40.328337Z","caller":"traceutil/trace.go:171","msg":"trace[177473914] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:25611; }","duration":"167.541266ms","start":"2026-05-27T20:34:40.160784Z","end":"2026-05-27T20:34:40.328325Z","steps":["trace[177473914] 'agreement among raft nodes before linearized reading' (duration: 167.446254ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:40.536063Z","caller":"traceutil/trace.go:171","msg":"trace[1801365721] transaction","detail":"{read_only:false; response_revision:25613; number_of_response:1; }","duration":"261.243398ms","start":"2026-05-27T20:34:40.274794Z","end":"2026-05-27T20:34:40.536038Z","steps":["trace[1801365721] 'process raft request' (duration: 261.139656ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:40.536346Z","caller":"traceutil/trace.go:171","msg":"trace[1829941406] transaction","detail":"{read_only:false; response_revision:25612; number_of_response:1; }","duration":"261.828846ms","start":"2026-05-27T20:34:40.274482Z","end":"2026-05-27T20:34:40.536311Z","steps":["trace[1829941406] 'process raft request' (duration: 240.515085ms)","trace[1829941406] 'compare' (duration: 20.763786ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:34:40.536467Z","caller":"traceutil/trace.go:171","msg":"trace[846651484] transaction","detail":"{read_only:false; response_revision:25614; number_of_response:1; }","duration":"259.929713ms","start":"2026-05-27T20:34:40.276506Z","end":"2026-05-27T20:34:40.536436Z","steps":["trace[846651484] 'process raft request' (duration: 259.478381ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:40.536514Z","caller":"traceutil/trace.go:171","msg":"trace[1972030345] linearizableReadLoop","detail":"{readStateIndex:26469; appliedIndex:26466; }","duration":"208.203563ms","start":"2026-05-27T20:34:40.328205Z","end":"2026-05-27T20:34:40.536409Z","steps":["trace[1972030345] 'read index received' (duration: 186.716008ms)","trace[1972030345] 'applied index is now lower than readState.Index' (duration: 21.485105ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:40.536608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.061578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-27T20:34:40.536746Z","caller":"traceutil/trace.go:171","msg":"trace[801394066] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:25614; }","duration":"216.256093ms","start":"2026-05-27T20:34:40.320473Z","end":"2026-05-27T20:34:40.53673Z","steps":["trace[801394066] 'agreement among raft nodes before linearized reading' (duration: 215.968585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:40.536752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.068936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-27T20:34:40.536868Z","caller":"traceutil/trace.go:171","msg":"trace[231894951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25614; }","duration":"242.22857ms","start":"2026-05-27T20:34:40.294615Z","end":"2026-05-27T20:34:40.536844Z","steps":["trace[231894951] 'agreement among raft nodes before linearized reading' (duration: 241.83759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:40.536615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.490257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:34:40.536977Z","caller":"traceutil/trace.go:171","msg":"trace[914765147] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:25614; }","duration":"264.872277ms","start":"2026-05-27T20:34:40.272088Z","end":"2026-05-27T20:34:40.53696Z","steps":["trace[914765147] 'agreement among raft nodes before linearized reading' (duration: 264.446146ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:40.688486Z","caller":"traceutil/trace.go:171","msg":"trace[2083227467] transaction","detail":"{read_only:false; response_revision:25615; number_of_response:1; }","duration":"146.83473ms","start":"2026-05-27T20:34:40.54162Z","end":"2026-05-27T20:34:40.688455Z","steps":["trace[2083227467] 'process raft request' (duration: 128.612734ms)","trace[2083227467] 'compare' (duration: 17.697302ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:34:40.688509Z","caller":"traceutil/trace.go:171","msg":"trace[1699932294] transaction","detail":"{read_only:false; response_revision:25616; number_of_response:1; }","duration":"146.613515ms","start":"2026-05-27T20:34:40.541869Z","end":"2026-05-27T20:34:40.688483Z","steps":["trace[1699932294] 'process raft request' (duration: 146.489672ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:41.190479Z","caller":"traceutil/trace.go:171","msg":"trace[2146627772] transaction","detail":"{read_only:false; response_revision:25618; number_of_response:1; }","duration":"119.380022ms","start":"2026-05-27T20:34:41.071069Z","end":"2026-05-27T20:34:41.190449Z","steps":["trace[2146627772] 'process raft request' (duration: 117.661916ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:41.383894Z","caller":"traceutil/trace.go:171","msg":"trace[1067872979] transaction","detail":"{read_only:false; response_revision:25619; number_of_response:1; }","duration":"145.107273ms","start":"2026-05-27T20:34:41.238736Z","end":"2026-05-27T20:34:41.383843Z","steps":["trace[1067872979] 'process raft request' (duration: 144.2397ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:41.579404Z","caller":"traceutil/trace.go:171","msg":"trace[1064770358] linearizableReadLoop","detail":"{readStateIndex:26476; appliedIndex:26474; }","duration":"172.775578ms","start":"2026-05-27T20:34:41.406601Z","end":"2026-05-27T20:34:41.579376Z","steps":["trace[1064770358] 'read index received' (duration: 52.749917ms)","trace[1064770358] 'applied index is now lower than readState.Index' (duration: 120.024421ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:34:41.579518Z","caller":"traceutil/trace.go:171","msg":"trace[242480806] transaction","detail":"{read_only:false; response_revision:25620; number_of_response:1; }","duration":"190.47087ms","start":"2026-05-27T20:34:41.389035Z","end":"2026-05-27T20:34:41.579506Z","steps":["trace[242480806] 'process raft request' (duration: 137.851597ms)","trace[242480806] 'compare' (duration: 52.244003ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:41.579652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.031175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-27T20:34:41.579732Z","caller":"traceutil/trace.go:171","msg":"trace[1548154557] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25620; }","duration":"173.133408ms","start":"2026-05-27T20:34:41.406563Z","end":"2026-05-27T20:34:41.579697Z","steps":["trace[1548154557] 'agreement among raft nodes before linearized reading' (duration: 172.939132ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:41.654915Z","caller":"traceutil/trace.go:171","msg":"trace[282212542] transaction","detail":"{read_only:false; response_revision:25621; number_of_response:1; }","duration":"194.032477ms","start":"2026-05-27T20:34:41.460855Z","end":"2026-05-27T20:34:41.654888Z","steps":["trace[282212542] 'process raft request' (duration: 193.898693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:41.655155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.926834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:34:41.655269Z","caller":"traceutil/trace.go:171","msg":"trace[1346817976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25621; }","duration":"176.119949ms","start":"2026-05-27T20:34:41.479123Z","end":"2026-05-27T20:34:41.655243Z","steps":["trace[1346817976] 'agreement among raft nodes before linearized reading' (duration: 175.76868ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:41.737002Z","caller":"traceutil/trace.go:171","msg":"trace[1155881271] transaction","detail":"{read_only:false; response_revision:25622; number_of_response:1; }","duration":"152.405244ms","start":"2026-05-27T20:34:41.58457Z","end":"2026-05-27T20:34:41.736976Z","steps":["trace[1155881271] 'process raft request' (duration: 151.429596ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:41.941101Z","caller":"traceutil/trace.go:171","msg":"trace[562294698] transaction","detail":"{read_only:false; response_revision:25623; number_of_response:1; }","duration":"195.985661ms","start":"2026-05-27T20:34:41.745083Z","end":"2026-05-27T20:34:41.941069Z","steps":["trace[562294698] 'process raft request' (duration: 123.708163ms)","trace[562294698] 'compare' (duration: 72.112824ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:42.226787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.759562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T20:34:42.227281Z","caller":"traceutil/trace.go:171","msg":"trace[2048364130] transaction","detail":"{read_only:false; response_revision:25624; number_of_response:1; }","duration":"364.3443ms","start":"2026-05-27T20:34:41.862876Z","end":"2026-05-27T20:34:42.227221Z","steps":["trace[2048364130] 'process raft request' (duration: 221.9734ms)","trace[2048364130] 'compare' (duration: 141.599488ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:34:42.227372Z","caller":"traceutil/trace.go:171","msg":"trace[865432774] transaction","detail":"{read_only:false; response_revision:25626; number_of_response:1; }","duration":"145.418923ms","start":"2026-05-27T20:34:42.081927Z","end":"2026-05-27T20:34:42.227346Z","steps":["trace[865432774] 'process raft request' (duration: 145.344801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:42.227498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:41.862845Z","time spent":"364.541236ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:34:42.227732Z","caller":"traceutil/trace.go:171","msg":"trace[661392146] transaction","detail":"{read_only:false; response_revision:25625; number_of_response:1; }","duration":"324.114173ms","start":"2026-05-27T20:34:41.903606Z","end":"2026-05-27T20:34:42.227721Z","steps":["trace[661392146] 'process raft request' (duration: 323.581128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:42.227853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:41.903591Z","time spent":"324.180114ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:34:42.675214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.12657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T20:34:42.675355Z","caller":"traceutil/trace.go:171","msg":"trace[1352204061] transaction","detail":"{read_only:false; response_revision:25628; number_of_response:1; }","duration":"340.519419ms","start":"2026-05-27T20:34:42.334808Z","end":"2026-05-27T20:34:42.675327Z","steps":["trace[1352204061] 'process raft request' (duration: 175.167593ms)","trace[1352204061] 'compare' (duration: 165.004617ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:42.67561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:42.33476Z","time spent":"340.647554ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:34:42.685812Z","caller":"traceutil/trace.go:171","msg":"trace[870861533] transaction","detail":"{read_only:false; response_revision:25629; number_of_response:1; }","duration":"143.12021ms","start":"2026-05-27T20:34:42.542672Z","end":"2026-05-27T20:34:42.685792Z","steps":["trace[870861533] 'process raft request' (duration: 143.000197ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:42.850802Z","caller":"traceutil/trace.go:171","msg":"trace[1120486008] linearizableReadLoop","detail":"{readStateIndex:26487; appliedIndex:26485; }","duration":"157.327967ms","start":"2026-05-27T20:34:42.693456Z","end":"2026-05-27T20:34:42.850784Z","steps":["trace[1120486008] 'read index received' (duration: 157.125182ms)","trace[1120486008] 'applied index is now lower than readState.Index' (duration: 201.335µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:34:42.850862Z","caller":"traceutil/trace.go:171","msg":"trace[1127948166] transaction","detail":"{read_only:false; response_revision:25630; number_of_response:1; }","duration":"307.746177ms","start":"2026-05-27T20:34:42.543056Z","end":"2026-05-27T20:34:42.850802Z","steps":["trace[1127948166] 'process raft request' (duration: 307.49607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:42.851017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.542593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-27T20:34:42.851048Z","caller":"traceutil/trace.go:171","msg":"trace[75589125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25631; }","duration":"157.624755ms","start":"2026-05-27T20:34:42.693416Z","end":"2026-05-27T20:34:42.85104Z","steps":["trace[75589125] 'agreement among raft nodes before linearized reading' (duration: 157.480151ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:42.850984Z","caller":"traceutil/trace.go:171","msg":"trace[1053022342] transaction","detail":"{read_only:false; response_revision:25631; number_of_response:1; }","duration":"307.815228ms","start":"2026-05-27T20:34:42.543116Z","end":"2026-05-27T20:34:42.850931Z","steps":["trace[1053022342] 'process raft request' (duration: 307.575812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:42.851205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:42.543012Z","time spent":"308.058355ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:34:42.851352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:42.543102Z","time spent":"308.110647ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:34:42.87584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.205335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-27T20:34:42.875933Z","caller":"traceutil/trace.go:171","msg":"trace[1179911381] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25632; }","duration":"182.460942ms","start":"2026-05-27T20:34:42.693449Z","end":"2026-05-27T20:34:42.87591Z","steps":["trace[1179911381] 'agreement among raft nodes before linearized reading' (duration: 182.131853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:43.110926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.887092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:34:43.11103Z","caller":"traceutil/trace.go:171","msg":"trace[1717969043] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25634; }","duration":"107.040457ms","start":"2026-05-27T20:34:43.003969Z","end":"2026-05-27T20:34:43.111009Z","steps":["trace[1717969043] 'range keys from in-memory index tree' (duration: 106.79517ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:43.312499Z","caller":"traceutil/trace.go:171","msg":"trace[2033056776] linearizableReadLoop","detail":"{readStateIndex:26491; appliedIndex:26490; }","duration":"197.06581ms","start":"2026-05-27T20:34:43.115399Z","end":"2026-05-27T20:34:43.312465Z","steps":["trace[2033056776] 'read index received' (duration: 196.752421ms)","trace[2033056776] 'applied index is now lower than readState.Index' (duration: 312.049µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:34:43.312538Z","caller":"traceutil/trace.go:171","msg":"trace[1928056100] transaction","detail":"{read_only:false; response_revision:25635; number_of_response:1; }","duration":"295.640077ms","start":"2026-05-27T20:34:43.01686Z","end":"2026-05-27T20:34:43.3125Z","steps":["trace[1928056100] 'process raft request' (duration: 295.374469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:43.312689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.271276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:34:43.312823Z","caller":"traceutil/trace.go:171","msg":"trace[844496125] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25635; }","duration":"197.538363ms","start":"2026-05-27T20:34:43.115264Z","end":"2026-05-27T20:34:43.312802Z","steps":["trace[844496125] 'agreement among raft nodes before linearized reading' (duration: 197.329077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:43.312847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.79824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-27T20:34:43.312894Z","caller":"traceutil/trace.go:171","msg":"trace[1011110726] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25635; }","duration":"117.919814ms","start":"2026-05-27T20:34:43.19496Z","end":"2026-05-27T20:34:43.312879Z","steps":["trace[1011110726] 'agreement among raft nodes before linearized reading' (duration: 117.694178ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:43.424719Z","caller":"traceutil/trace.go:171","msg":"trace[1174508598] linearizableReadLoop","detail":"{readStateIndex:26492; appliedIndex:26491; }","duration":"106.042741ms","start":"2026-05-27T20:34:43.318637Z","end":"2026-05-27T20:34:43.42468Z","steps":["trace[1174508598] 'read index received' (duration: 95.994716ms)","trace[1174508598] 'applied index is now lower than readState.Index' (duration: 10.046924ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:34:43.424856Z","caller":"traceutil/trace.go:171","msg":"trace[2101411706] transaction","detail":"{read_only:false; response_revision:25636; number_of_response:1; }","duration":"106.681397ms","start":"2026-05-27T20:34:43.318161Z","end":"2026-05-27T20:34:43.424842Z","steps":["trace[2101411706] 'process raft request' (duration: 96.601592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:43.425229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.575284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18420"} {"level":"info","ts":"2026-05-27T20:34:43.42534Z","caller":"traceutil/trace.go:171","msg":"trace[22305342] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25636; }","duration":"106.734519ms","start":"2026-05-27T20:34:43.318586Z","end":"2026-05-27T20:34:43.425321Z","steps":["trace[22305342] 'agreement among raft nodes before linearized reading' (duration: 106.388659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:49.535714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.770068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T20:34:49.535986Z","caller":"traceutil/trace.go:171","msg":"trace[1935479263] transaction","detail":"{read_only:false; response_revision:25734; number_of_response:1; }","duration":"202.083718ms","start":"2026-05-27T20:34:49.333879Z","end":"2026-05-27T20:34:49.535962Z","steps":["trace[1935479263] 'process raft request' (duration: 75.942109ms)","trace[1935479263] 'compare' (duration: 124.871904ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:34:49.696523Z","caller":"traceutil/trace.go:171","msg":"trace[137985631] transaction","detail":"{read_only:false; response_revision:25736; number_of_response:1; }","duration":"155.2022ms","start":"2026-05-27T20:34:49.541287Z","end":"2026-05-27T20:34:49.696489Z","steps":["trace[137985631] 'process raft request' (duration: 134.487245ms)","trace[137985631] 'compare' (duration: 20.14007ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:34:49.976361Z","caller":"traceutil/trace.go:171","msg":"trace[1415801681] transaction","detail":"{read_only:false; response_revision:25737; number_of_response:1; }","duration":"271.1826ms","start":"2026-05-27T20:34:49.705154Z","end":"2026-05-27T20:34:49.976336Z","steps":["trace[1415801681] 'process raft request' (duration: 271.023316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:50.145941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.888692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-27T20:34:50.146011Z","caller":"traceutil/trace.go:171","msg":"trace[1937296924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25737; }","duration":"129.012375ms","start":"2026-05-27T20:34:50.016984Z","end":"2026-05-27T20:34:50.145996Z","steps":["trace[1937296924] 'range keys from in-memory index tree' (duration: 128.642506ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:50.20803Z","caller":"traceutil/trace.go:171","msg":"trace[6345351] linearizableReadLoop","detail":"{readStateIndex:26596; appliedIndex:26595; }","duration":"101.684301ms","start":"2026-05-27T20:34:50.106321Z","end":"2026-05-27T20:34:50.208005Z","steps":["trace[6345351] 'read index received' (duration: 101.550227ms)","trace[6345351] 'applied index is now lower than readState.Index' (duration: 132.664µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:34:50.208135Z","caller":"traceutil/trace.go:171","msg":"trace[1975129665] transaction","detail":"{read_only:false; response_revision:25738; number_of_response:1; }","duration":"118.853698ms","start":"2026-05-27T20:34:50.08925Z","end":"2026-05-27T20:34:50.208104Z","steps":["trace[1975129665] 'process raft request' (duration: 118.589651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:50.208192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.853335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:34:50.208393Z","caller":"traceutil/trace.go:171","msg":"trace[1257653330] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25738; }","duration":"102.066471ms","start":"2026-05-27T20:34:50.106302Z","end":"2026-05-27T20:34:50.208368Z","steps":["trace[1257653330] 'agreement among raft nodes before linearized reading' (duration: 101.823744ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:50.479577Z","caller":"traceutil/trace.go:171","msg":"trace[31682001] transaction","detail":"{read_only:false; response_revision:25739; number_of_response:1; }","duration":"329.394486ms","start":"2026-05-27T20:34:50.150158Z","end":"2026-05-27T20:34:50.479553Z","steps":["trace[31682001] 'process raft request' (duration: 329.208231ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:50.479622Z","caller":"traceutil/trace.go:171","msg":"trace[451828977] linearizableReadLoop","detail":"{readStateIndex:26597; appliedIndex:26597; }","duration":"271.492589ms","start":"2026-05-27T20:34:50.208102Z","end":"2026-05-27T20:34:50.479595Z","steps":["trace[451828977] 'read index received' (duration: 271.482519ms)","trace[451828977] 'applied index is now lower than readState.Index' (duration: 8.32µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:50.479753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:50.150144Z","time spent":"329.513269ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:34:50.479954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.358897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:34:50.480088Z","caller":"traceutil/trace.go:171","msg":"trace[1204121644] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:25739; }","duration":"336.579954ms","start":"2026-05-27T20:34:50.143478Z","end":"2026-05-27T20:34:50.480058Z","steps":["trace[1204121644] 'agreement among raft nodes before linearized reading' (duration: 336.253154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:50.480158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:50.143457Z","time spent":"336.689046ms","remote":"127.0.0.1:37174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-27T20:34:50.570672Z","caller":"traceutil/trace.go:171","msg":"trace[1441985153] transaction","detail":"{read_only:false; response_revision:25740; number_of_response:1; }","duration":"305.392392ms","start":"2026-05-27T20:34:50.265227Z","end":"2026-05-27T20:34:50.57062Z","steps":["trace[1441985153] 'process raft request' (duration: 291.471542ms)","trace[1441985153] 'compare' (duration: 13.739135ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:50.570905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:50.265208Z","time spent":"305.579018ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:34:50.570949Z","caller":"traceutil/trace.go:171","msg":"trace[1487380374] transaction","detail":"{read_only:false; response_revision:25742; number_of_response:1; }","duration":"187.065109ms","start":"2026-05-27T20:34:50.383832Z","end":"2026-05-27T20:34:50.570897Z","steps":["trace[1487380374] 'process raft request' (duration: 186.946105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:50.571062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.752275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-05-27T20:34:50.571143Z","caller":"traceutil/trace.go:171","msg":"trace[607072417] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25743; }","duration":"362.911561ms","start":"2026-05-27T20:34:50.208213Z","end":"2026-05-27T20:34:50.571124Z","steps":["trace[607072417] 'agreement among raft nodes before linearized reading' (duration: 362.675114ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:50.571169Z","caller":"traceutil/trace.go:171","msg":"trace[222642543] transaction","detail":"{read_only:false; response_revision:25741; number_of_response:1; }","duration":"305.794524ms","start":"2026-05-27T20:34:50.265363Z","end":"2026-05-27T20:34:50.571158Z","steps":["trace[222642543] 'process raft request' (duration: 305.231718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:50.571182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:50.208186Z","time spent":"362.987862ms","remote":"127.0.0.1:36286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3332,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-27T20:34:50.571231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:50.26534Z","time spent":"305.855795ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:34:50.571273Z","caller":"traceutil/trace.go:171","msg":"trace[2099557317] transaction","detail":"{read_only:false; response_revision:25743; number_of_response:1; }","duration":"160.937705ms","start":"2026-05-27T20:34:50.410314Z","end":"2026-05-27T20:34:50.571252Z","steps":["trace[2099557317] 'process raft request' (duration: 160.518654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:50.571247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.617613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-27T20:34:50.571345Z","caller":"traceutil/trace.go:171","msg":"trace[1725307463] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:25743; }","duration":"209.790167ms","start":"2026-05-27T20:34:50.361533Z","end":"2026-05-27T20:34:50.571323Z","steps":["trace[1725307463] 'agreement among raft nodes before linearized reading' (duration: 209.613822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:50.57152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.08171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:34:50.571586Z","caller":"traceutil/trace.go:171","msg":"trace[1455629252] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25743; }","duration":"357.203884ms","start":"2026-05-27T20:34:50.214371Z","end":"2026-05-27T20:34:50.571575Z","steps":["trace[1455629252] 'agreement among raft nodes before linearized reading' (duration: 357.024259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:50.571636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:50.214354Z","time spent":"357.274876ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-05-27T20:34:50.571661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.028846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c8baebac-b415-4454-8ad7-03898f4e35b3\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-05-27T20:34:50.57175Z","caller":"traceutil/trace.go:171","msg":"trace[1100772761] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c8baebac-b415-4454-8ad7-03898f4e35b3; range_end:; response_count:1; response_revision:25743; }","duration":"318.124458ms","start":"2026-05-27T20:34:50.253587Z","end":"2026-05-27T20:34:50.571711Z","steps":["trace[1100772761] 'agreement among raft nodes before linearized reading' (duration: 317.598434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:50.571842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:50.253577Z","time spent":"318.252953ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3784,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c8baebac-b415-4454-8ad7-03898f4e35b3\" "} {"level":"info","ts":"2026-05-27T20:34:56.849383Z","caller":"traceutil/trace.go:171","msg":"trace[1821282284] transaction","detail":"{read_only:false; response_revision:25807; number_of_response:1; }","duration":"250.623179ms","start":"2026-05-27T20:34:56.598744Z","end":"2026-05-27T20:34:56.849367Z","steps":["trace[1821282284] 'process raft request' (duration: 250.587388ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:56.849602Z","caller":"traceutil/trace.go:171","msg":"trace[594023069] transaction","detail":"{read_only:false; response_revision:25806; number_of_response:1; }","duration":"251.825702ms","start":"2026-05-27T20:34:56.597726Z","end":"2026-05-27T20:34:56.849552Z","steps":["trace[594023069] 'process raft request' (duration: 251.490913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:57.096755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.677059ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T20:34:57.096995Z","caller":"traceutil/trace.go:171","msg":"trace[1022965584] transaction","detail":"{read_only:false; response_revision:25808; number_of_response:1; }","duration":"372.47002ms","start":"2026-05-27T20:34:56.724493Z","end":"2026-05-27T20:34:57.096963Z","steps":["trace[1022965584] 'process raft request' (duration: 164.502872ms)","trace[1022965584] 'compare' (duration: 207.578836ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:57.097179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:56.724465Z","time spent":"372.631595ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:34:57.097709Z","caller":"traceutil/trace.go:171","msg":"trace[241601529] transaction","detail":"{read_only:false; response_revision:25809; number_of_response:1; }","duration":"369.782647ms","start":"2026-05-27T20:34:56.727889Z","end":"2026-05-27T20:34:57.097671Z","steps":["trace[241601529] 'process raft request' (duration: 368.935633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:57.097844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:56.72784Z","time spent":"369.930471ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:34:57.359641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.759661ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-27T20:34:57.359858Z","caller":"traceutil/trace.go:171","msg":"trace[2124786171] linearizableReadLoop","detail":"{readStateIndex:26668; appliedIndex:26664; }","duration":"618.425792ms","start":"2026-05-27T20:34:56.741418Z","end":"2026-05-27T20:34:57.359844Z","steps":["trace[2124786171] 'read index received' (duration: 107.813768ms)","trace[2124786171] 'applied index is now lower than readState.Index' (duration: 510.611354ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:57.360035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.607047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-27T20:34:57.360059Z","caller":"traceutil/trace.go:171","msg":"trace[715902635] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25809; }","duration":"618.663059ms","start":"2026-05-27T20:34:56.74139Z","end":"2026-05-27T20:34:57.360053Z","steps":["trace[715902635] 'agreement among raft nodes before linearized reading' (duration: 618.556626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:57.360088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:56.741379Z","time spent":"618.70086ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-05-27T20:34:57.360399Z","caller":"traceutil/trace.go:171","msg":"trace[923339321] transaction","detail":"{read_only:false; response_revision:25810; number_of_response:1; }","duration":"457.855478ms","start":"2026-05-27T20:34:56.902536Z","end":"2026-05-27T20:34:57.360391Z","steps":["trace[923339321] 'process raft request' (duration: 457.203339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:57.360475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:56.902526Z","time spent":"457.897989ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:34:57.377898Z","caller":"traceutil/trace.go:171","msg":"trace[1004736956] transaction","detail":"{read_only:false; response_revision:25811; number_of_response:1; }","duration":"473.838203ms","start":"2026-05-27T20:34:56.90403Z","end":"2026-05-27T20:34:57.377868Z","steps":["trace[1004736956] 'process raft request' (duration: 456.354167ms)","trace[1004736956] 'compare' (duration: 17.26172ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:57.378028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:56.904Z","time spent":"473.950026ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:34:57.378108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.086782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-27T20:34:57.378156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.866969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10359"} {"level":"warn","ts":"2026-05-27T20:34:57.378178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.42248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-27T20:34:57.378217Z","caller":"traceutil/trace.go:171","msg":"trace[1626027444] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25811; }","duration":"502.222466ms","start":"2026-05-27T20:34:56.875974Z","end":"2026-05-27T20:34:57.378197Z","steps":["trace[1626027444] 'agreement among raft nodes before linearized reading' (duration: 501.909747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:57.378235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.910431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-27T20:34:57.378264Z","caller":"traceutil/trace.go:171","msg":"trace[741437931] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25811; }","duration":"592.031373ms","start":"2026-05-27T20:34:56.786206Z","end":"2026-05-27T20:34:57.378237Z","steps":["trace[741437931] 'agreement among raft nodes before linearized reading' (duration: 591.656643ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:57.378279Z","caller":"traceutil/trace.go:171","msg":"trace[447179974] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25811; }","duration":"547.982734ms","start":"2026-05-27T20:34:56.830284Z","end":"2026-05-27T20:34:57.378267Z","steps":["trace[447179974] 'agreement among raft nodes before linearized reading' (duration: 547.808049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:57.378283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:56.875946Z","time spent":"502.325549ms","remote":"127.0.0.1:59558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "} {"level":"info","ts":"2026-05-27T20:34:57.378286Z","caller":"traceutil/trace.go:171","msg":"trace[755159297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25811; }","duration":"448.563103ms","start":"2026-05-27T20:34:56.929698Z","end":"2026-05-27T20:34:57.378261Z","steps":["trace[755159297] 'agreement among raft nodes before linearized reading' (duration: 448.169693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:57.37831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:56.830273Z","time spent":"548.032225ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1883,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" "} {"level":"warn","ts":"2026-05-27T20:34:57.378332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:56.786199Z","time spent":"592.114145ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10382,"request content":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" "} {"level":"warn","ts":"2026-05-27T20:34:57.378345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:56.929688Z","time spent":"448.645836ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T20:34:57.378393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.527571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-27T20:34:57.378421Z","caller":"traceutil/trace.go:171","msg":"trace[1839186239] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25811; }","duration":"416.598374ms","start":"2026-05-27T20:34:56.961816Z","end":"2026-05-27T20:34:57.378414Z","steps":["trace[1839186239] 'agreement among raft nodes before linearized reading' (duration: 416.48328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:34:57.378437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:34:56.961799Z","time spent":"416.634274ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-27T20:34:57.488318Z","caller":"traceutil/trace.go:171","msg":"trace[560103852] transaction","detail":"{read_only:false; response_revision:25812; number_of_response:1; }","duration":"123.368842ms","start":"2026-05-27T20:34:57.36492Z","end":"2026-05-27T20:34:57.488289Z","steps":["trace[560103852] 'process raft request' (duration: 123.198328ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:57.521341Z","caller":"traceutil/trace.go:171","msg":"trace[1885538339] transaction","detail":"{read_only:false; response_revision:25813; number_of_response:1; }","duration":"139.834621ms","start":"2026-05-27T20:34:57.381461Z","end":"2026-05-27T20:34:57.521295Z","steps":["trace[1885538339] 'process raft request' (duration: 139.520033ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:57.521655Z","caller":"traceutil/trace.go:171","msg":"trace[39671644] transaction","detail":"{read_only:false; response_revision:25814; number_of_response:1; }","duration":"139.548083ms","start":"2026-05-27T20:34:57.382078Z","end":"2026-05-27T20:34:57.521626Z","steps":["trace[39671644] 'process raft request' (duration: 139.163663ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:57.521876Z","caller":"traceutil/trace.go:171","msg":"trace[658508409] transaction","detail":"{read_only:false; response_revision:25815; number_of_response:1; }","duration":"133.023004ms","start":"2026-05-27T20:34:57.388836Z","end":"2026-05-27T20:34:57.521859Z","steps":["trace[658508409] 'process raft request' (duration: 132.699266ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:57.52186Z","caller":"traceutil/trace.go:171","msg":"trace[815528168] linearizableReadLoop","detail":"{readStateIndex:26673; appliedIndex:26671; }","duration":"140.006986ms","start":"2026-05-27T20:34:57.381827Z","end":"2026-05-27T20:34:57.521834Z","steps":["trace[815528168] 'read index received' (duration: 106.39428ms)","trace[815528168] 'applied index is now lower than readState.Index' (duration: 33.611116ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:34:57.522061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.927522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-27T20:34:57.522065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.230281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-c8baebac-b415-4454-8ad7-03898f4e35b3\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-27T20:34:57.522115Z","caller":"traceutil/trace.go:171","msg":"trace[625423797] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:25815; }","duration":"137.037045ms","start":"2026-05-27T20:34:57.385062Z","end":"2026-05-27T20:34:57.522099Z","steps":["trace[625423797] 'agreement among raft nodes before linearized reading' (duration: 136.929152ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:34:57.522127Z","caller":"traceutil/trace.go:171","msg":"trace[1434839703] range","detail":"{range_begin:/registry/persistentvolumes/pvc-c8baebac-b415-4454-8ad7-03898f4e35b3; range_end:; response_count:1; response_revision:25815; }","duration":"140.324064ms","start":"2026-05-27T20:34:57.38179Z","end":"2026-05-27T20:34:57.522114Z","steps":["trace[1434839703] 'agreement among raft nodes before linearized reading' (duration: 140.124689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:35:01.642235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.07764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T20:35:01.642532Z","caller":"traceutil/trace.go:171","msg":"trace[220828694] linearizableReadLoop","detail":"{readStateIndex:26727; appliedIndex:26726; }","duration":"259.705197ms","start":"2026-05-27T20:35:01.382801Z","end":"2026-05-27T20:35:01.642506Z","steps":["trace[220828694] 'read index received' (duration: 61.060844ms)","trace[220828694] 'applied index is now lower than readState.Index' (duration: 198.642893ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:35:01.642639Z","caller":"traceutil/trace.go:171","msg":"trace[1302103673] transaction","detail":"{read_only:false; response_revision:25866; number_of_response:1; }","duration":"261.508036ms","start":"2026-05-27T20:35:01.381114Z","end":"2026-05-27T20:35:01.642622Z","steps":["trace[1302103673] 'process raft request' (duration: 121.907551ms)","trace[1302103673] 'compare' (duration: 138.848164ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:35:01.642869Z","caller":"traceutil/trace.go:171","msg":"trace[355732156] transaction","detail":"{read_only:false; response_revision:25867; number_of_response:1; }","duration":"249.229431ms","start":"2026-05-27T20:35:01.393605Z","end":"2026-05-27T20:35:01.642835Z","steps":["trace[355732156] 'process raft request' (duration: 248.760159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:35:01.643064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.244952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/openstack/container-infra\" ","response":"range_response_count:1 size:729"} {"level":"info","ts":"2026-05-27T20:35:01.64315Z","caller":"traceutil/trace.go:171","msg":"trace[684722066] range","detail":"{range_begin:/registry/ingress/openstack/container-infra; range_end:; response_count:1; response_revision:25867; }","duration":"260.373425ms","start":"2026-05-27T20:35:01.382759Z","end":"2026-05-27T20:35:01.643132Z","steps":["trace[684722066] 'agreement among raft nodes before linearized reading' (duration: 260.102368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:35:01.660677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.040115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-27T20:35:01.660748Z","caller":"traceutil/trace.go:171","msg":"trace[627881373] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25868; }","duration":"119.169678ms","start":"2026-05-27T20:35:01.541567Z","end":"2026-05-27T20:35:01.660736Z","steps":["trace[627881373] 'agreement among raft nodes before linearized reading' (duration: 118.982033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:35:01.660786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.186758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-27T20:35:01.660885Z","caller":"traceutil/trace.go:171","msg":"trace[462439968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25868; }","duration":"119.341422ms","start":"2026-05-27T20:35:01.541523Z","end":"2026-05-27T20:35:01.660865Z","steps":["trace[462439968] 'agreement among raft nodes before linearized reading' (duration: 119.031454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:35:01.660925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.062859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/openstack/container-infra-registry\" ","response":"range_response_count:1 size:784"} {"level":"info","ts":"2026-05-27T20:35:01.660986Z","caller":"traceutil/trace.go:171","msg":"trace[1047031610] range","detail":"{range_begin:/registry/ingress/openstack/container-infra-registry; range_end:; response_count:1; response_revision:25868; }","duration":"278.12475ms","start":"2026-05-27T20:35:01.382847Z","end":"2026-05-27T20:35:01.660972Z","steps":["trace[1047031610] 'agreement among raft nodes before linearized reading' (duration: 277.806602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:35:01.660951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.766817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:35:01.661251Z","caller":"traceutil/trace.go:171","msg":"trace[2047490184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25868; }","duration":"181.139967ms","start":"2026-05-27T20:35:01.480099Z","end":"2026-05-27T20:35:01.661239Z","steps":["trace[2047490184] 'agreement among raft nodes before linearized reading' (duration: 180.52535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:35:01.661087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.774454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-27T20:35:01.661344Z","caller":"traceutil/trace.go:171","msg":"trace[249832300] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:25868; }","duration":"138.025951ms","start":"2026-05-27T20:35:01.523303Z","end":"2026-05-27T20:35:01.661329Z","steps":["trace[249832300] 'agreement among raft nodes before linearized reading' (duration: 137.757944ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:35:01.887868Z","caller":"traceutil/trace.go:171","msg":"trace[383039602] transaction","detail":"{read_only:false; response_revision:25870; number_of_response:1; }","duration":"222.869184ms","start":"2026-05-27T20:35:01.664973Z","end":"2026-05-27T20:35:01.887842Z","steps":["trace[383039602] 'process raft request' (duration: 222.612547ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:35:01.888003Z","caller":"traceutil/trace.go:171","msg":"trace[573242410] transaction","detail":"{read_only:false; response_revision:25871; number_of_response:1; }","duration":"221.28439ms","start":"2026-05-27T20:35:01.666685Z","end":"2026-05-27T20:35:01.887969Z","steps":["trace[573242410] 'process raft request' (duration: 221.052464ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:35:01.888842Z","caller":"traceutil/trace.go:171","msg":"trace[376737545] linearizableReadLoop","detail":"{readStateIndex:26734; appliedIndex:26730; }","duration":"166.674181ms","start":"2026-05-27T20:35:01.722153Z","end":"2026-05-27T20:35:01.888827Z","steps":["trace[376737545] 'read index received' (duration: 165.370116ms)","trace[376737545] 'applied index is now lower than readState.Index' (duration: 1.302795ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:35:01.888951Z","caller":"traceutil/trace.go:171","msg":"trace[1325023815] transaction","detail":"{read_only:false; response_revision:25873; number_of_response:1; }","duration":"218.154485ms","start":"2026-05-27T20:35:01.670786Z","end":"2026-05-27T20:35:01.88894Z","steps":["trace[1325023815] 'process raft request' (duration: 217.774145ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:35:01.888972Z","caller":"traceutil/trace.go:171","msg":"trace[413704905] transaction","detail":"{read_only:false; response_revision:25872; number_of_response:1; }","duration":"219.364448ms","start":"2026-05-27T20:35:01.669594Z","end":"2026-05-27T20:35:01.888958Z","steps":["trace[413704905] 'process raft request' (duration: 218.867844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:35:01.889202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.600065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-27T20:35:01.88924Z","caller":"traceutil/trace.go:171","msg":"trace[798127649] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:25873; }","duration":"164.667777ms","start":"2026-05-27T20:35:01.724561Z","end":"2026-05-27T20:35:01.889229Z","steps":["trace[798127649] 'agreement among raft nodes before linearized reading' (duration: 164.513233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:35:01.889447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.225597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-27T20:35:01.889479Z","caller":"traceutil/trace.go:171","msg":"trace[2075483105] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:25873; }","duration":"120.259677ms","start":"2026-05-27T20:35:01.769209Z","end":"2026-05-27T20:35:01.889468Z","steps":["trace[2075483105] 'agreement among raft nodes before linearized reading' (duration: 120.182825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:35:01.88957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.482769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:35:01.889609Z","caller":"traceutil/trace.go:171","msg":"trace[1274732726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25873; }","duration":"227.54342ms","start":"2026-05-27T20:35:01.662055Z","end":"2026-05-27T20:35:01.889598Z","steps":["trace[1274732726] 'agreement among raft nodes before linearized reading' (duration: 227.460048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:35:01.889692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.11358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-27T20:35:01.889727Z","caller":"traceutil/trace.go:171","msg":"trace[1023785067] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25873; }","duration":"139.189752ms","start":"2026-05-27T20:35:01.750528Z","end":"2026-05-27T20:35:01.889718Z","steps":["trace[1023785067] 'agreement among raft nodes before linearized reading' (duration: 139.09659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:35:01.889922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.502795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra.18b386661a66c121\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-05-27T20:35:01.889953Z","caller":"traceutil/trace.go:171","msg":"trace[833222430] range","detail":"{range_begin:/registry/events/openstack/container-infra.18b386661a66c121; range_end:; response_count:1; response_revision:25873; }","duration":"163.538796ms","start":"2026-05-27T20:35:01.726403Z","end":"2026-05-27T20:35:01.889941Z","steps":["trace[833222430] 'agreement among raft nodes before linearized reading' (duration: 163.447044ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:35:04.805519Z","caller":"traceutil/trace.go:171","msg":"trace[1888100089] transaction","detail":"{read_only:false; response_revision:25897; number_of_response:1; }","duration":"118.519529ms","start":"2026-05-27T20:35:04.686961Z","end":"2026-05-27T20:35:04.805481Z","steps":["trace[1888100089] 'process raft request' (duration: 85.265803ms)","trace[1888100089] 'compare' (duration: 32.811534ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:35:05.751005Z","caller":"traceutil/trace.go:171","msg":"trace[1519837324] linearizableReadLoop","detail":"{readStateIndex:26767; appliedIndex:26767; }","duration":"105.6507ms","start":"2026-05-27T20:35:05.645338Z","end":"2026-05-27T20:35:05.750988Z","steps":["trace[1519837324] 'read index received' (duration: 105.64075ms)","trace[1519837324] 'applied index is now lower than readState.Index' (duration: 7.87µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:35:05.750988Z","caller":"traceutil/trace.go:171","msg":"trace[1085005300] transaction","detail":"{read_only:false; response_revision:25906; number_of_response:1; }","duration":"106.372299ms","start":"2026-05-27T20:35:05.644585Z","end":"2026-05-27T20:35:05.750958Z","steps":["trace[1085005300] 'process raft request' (duration: 106.195134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:35:05.751262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.898156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-05-27T20:35:05.751298Z","caller":"traceutil/trace.go:171","msg":"trace[200892582] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25906; }","duration":"105.971498ms","start":"2026-05-27T20:35:05.645318Z","end":"2026-05-27T20:35:05.751289Z","steps":["trace[200892582] 'agreement among raft nodes before linearized reading' (duration: 105.751912ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:35:10.205189Z","caller":"traceutil/trace.go:171","msg":"trace[30071156] transaction","detail":"{read_only:false; response_revision:25946; number_of_response:1; }","duration":"124.630307ms","start":"2026-05-27T20:35:10.080525Z","end":"2026-05-27T20:35:10.205156Z","steps":["trace[30071156] 'process raft request' (duration: 124.37555ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:35:13.16063Z","caller":"traceutil/trace.go:171","msg":"trace[143558551] transaction","detail":"{read_only:false; response_revision:25973; number_of_response:1; }","duration":"242.403427ms","start":"2026-05-27T20:35:12.9182Z","end":"2026-05-27T20:35:13.160603Z","steps":["trace[143558551] 'process raft request' (duration: 228.528538ms)","trace[143558551] 'compare' (duration: 13.521209ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:35:13.161185Z","caller":"traceutil/trace.go:171","msg":"trace[590033749] transaction","detail":"{read_only:false; response_revision:25974; number_of_response:1; }","duration":"241.620375ms","start":"2026-05-27T20:35:12.919545Z","end":"2026-05-27T20:35:13.161165Z","steps":["trace[590033749] 'process raft request' (duration: 241.007488ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:35:13.161339Z","caller":"traceutil/trace.go:171","msg":"trace[611122463] transaction","detail":"{read_only:false; response_revision:25975; number_of_response:1; }","duration":"225.154415ms","start":"2026-05-27T20:35:12.93614Z","end":"2026-05-27T20:35:13.161295Z","steps":["trace[611122463] 'process raft request' (duration: 224.617481ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:35:13.161354Z","caller":"traceutil/trace.go:171","msg":"trace[2042059192] linearizableReadLoop","detail":"{readStateIndex:26838; appliedIndex:26835; }","duration":"112.367893ms","start":"2026-05-27T20:35:13.04894Z","end":"2026-05-27T20:35:13.161308Z","steps":["trace[2042059192] 'read index received' (duration: 97.803996ms)","trace[2042059192] 'applied index is now lower than readState.Index' (duration: 14.560556ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:35:13.161551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.592798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-05-27T20:35:13.161606Z","caller":"traceutil/trace.go:171","msg":"trace[789554179] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25976; }","duration":"112.712681ms","start":"2026-05-27T20:35:13.048878Z","end":"2026-05-27T20:35:13.161591Z","steps":["trace[789554179] 'agreement among raft nodes before linearized reading' (duration: 112.512736ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:35:14.705709Z","caller":"traceutil/trace.go:171","msg":"trace[1395253413] transaction","detail":"{read_only:false; response_revision:25986; number_of_response:1; }","duration":"133.065916ms","start":"2026-05-27T20:35:14.572585Z","end":"2026-05-27T20:35:14.705651Z","steps":["trace[1395253413] 'process raft request' (duration: 132.814449ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:35:17.360775Z","caller":"traceutil/trace.go:171","msg":"trace[502609840] transaction","detail":"{read_only:false; response_revision:26015; number_of_response:1; }","duration":"114.800059ms","start":"2026-05-27T20:35:17.24594Z","end":"2026-05-27T20:35:17.360741Z","steps":["trace[502609840] 'process raft request' (duration: 114.602593ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:35:17.360965Z","caller":"traceutil/trace.go:171","msg":"trace[850833913] transaction","detail":"{read_only:false; response_revision:26016; number_of_response:1; }","duration":"108.978229ms","start":"2026-05-27T20:35:17.251937Z","end":"2026-05-27T20:35:17.360915Z","steps":["trace[850833913] 'process raft request' (duration: 108.753573ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:35:20.873504Z","caller":"traceutil/trace.go:171","msg":"trace[655430832] linearizableReadLoop","detail":"{readStateIndex:26904; appliedIndex:26903; }","duration":"103.773428ms","start":"2026-05-27T20:35:20.769706Z","end":"2026-05-27T20:35:20.87348Z","steps":["trace[655430832] 'read index received' (duration: 92.84833ms)","trace[655430832] 'applied index is now lower than readState.Index' (duration: 10.923728ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:35:20.873793Z","caller":"traceutil/trace.go:171","msg":"trace[681415083] transaction","detail":"{read_only:false; response_revision:26040; number_of_response:1; }","duration":"127.804783ms","start":"2026-05-27T20:35:20.745974Z","end":"2026-05-27T20:35:20.873778Z","steps":["trace[681415083] 'process raft request' (duration: 116.581387ms)","trace[681415083] 'compare' (duration: 10.731963ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:35:20.874033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.304862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-27T20:35:20.874079Z","caller":"traceutil/trace.go:171","msg":"trace[1212335058] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:26040; }","duration":"104.410715ms","start":"2026-05-27T20:35:20.769654Z","end":"2026-05-27T20:35:20.874065Z","steps":["trace[1212335058] 'agreement among raft nodes before linearized reading' (duration: 104.317382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:35:26.574084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.647129ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-27T20:35:29.063571Z","caller":"traceutil/trace.go:171","msg":"trace[677846288] transaction","detail":"{read_only:false; response_revision:26100; number_of_response:1; }","duration":"109.828324ms","start":"2026-05-27T20:35:28.95371Z","end":"2026-05-27T20:35:29.063538Z","steps":["trace[677846288] 'process raft request' (duration: 109.607617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:35:29.499974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.824499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T20:35:29.500285Z","caller":"traceutil/trace.go:171","msg":"trace[1111471366] transaction","detail":"{read_only:false; response_revision:26102; number_of_response:1; }","duration":"185.307549ms","start":"2026-05-27T20:35:29.314955Z","end":"2026-05-27T20:35:29.500262Z","steps":["trace[1111471366] 'process raft request' (duration: 185.163455ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:35:29.500302Z","caller":"traceutil/trace.go:171","msg":"trace[760502151] transaction","detail":"{read_only:false; response_revision:26101; number_of_response:1; }","duration":"389.530176ms","start":"2026-05-27T20:35:29.11071Z","end":"2026-05-27T20:35:29.50024Z","steps":["trace[760502151] 'process raft request' (duration: 34.186243ms)","trace[760502151] 'compare' (duration: 353.785381ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:35:29.50078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:35:29.110683Z","time spent":"389.798662ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:35:29.520839Z","caller":"traceutil/trace.go:171","msg":"trace[2128814190] linearizableReadLoop","detail":"{readStateIndex:26971; appliedIndex:26966; }","duration":"102.411192ms","start":"2026-05-27T20:35:29.418395Z","end":"2026-05-27T20:35:29.520806Z","steps":["trace[2128814190] 'read index received' (duration: 81.553183ms)","trace[2128814190] 'applied index is now lower than readState.Index' (duration: 20.856589ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:35:29.521145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.72359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-27T20:35:29.521182Z","caller":"traceutil/trace.go:171","msg":"trace[2014732009] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26105; }","duration":"102.829393ms","start":"2026-05-27T20:35:29.418342Z","end":"2026-05-27T20:35:29.521171Z","steps":["trace[2014732009] 'agreement among raft nodes before linearized reading' (duration: 102.600436ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:35:29.521589Z","caller":"traceutil/trace.go:171","msg":"trace[1953433848] transaction","detail":"{read_only:false; response_revision:26103; number_of_response:1; }","duration":"204.561444ms","start":"2026-05-27T20:35:29.317016Z","end":"2026-05-27T20:35:29.521578Z","steps":["trace[1953433848] 'process raft request' (duration: 203.594018ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:35:29.521736Z","caller":"traceutil/trace.go:171","msg":"trace[1838050428] transaction","detail":"{read_only:false; response_revision:26104; number_of_response:1; }","duration":"119.593589ms","start":"2026-05-27T20:35:29.40213Z","end":"2026-05-27T20:35:29.521723Z","steps":["trace[1838050428] 'process raft request' (duration: 118.595913ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:35:29.521849Z","caller":"traceutil/trace.go:171","msg":"trace[701647087] transaction","detail":"{read_only:false; response_revision:26105; number_of_response:1; }","duration":"118.869558ms","start":"2026-05-27T20:35:29.40297Z","end":"2026-05-27T20:35:29.521839Z","steps":["trace[701647087] 'process raft request' (duration: 117.79783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:35:29.742663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.081483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:35:29.742783Z","caller":"traceutil/trace.go:171","msg":"trace[111310167] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26106; }","duration":"120.266718ms","start":"2026-05-27T20:35:29.622485Z","end":"2026-05-27T20:35:29.742752Z","steps":["trace[111310167] 'range keys from in-memory index tree' (duration: 119.97116ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:35:30.054247Z","caller":"traceutil/trace.go:171","msg":"trace[1741630295] transaction","detail":"{read_only:false; response_revision:26107; number_of_response:1; }","duration":"130.619279ms","start":"2026-05-27T20:35:29.923581Z","end":"2026-05-27T20:35:30.054201Z","steps":["trace[1741630295] 'process raft request' (duration: 130.400683ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:35:30.090408Z","caller":"traceutil/trace.go:171","msg":"trace[695160133] transaction","detail":"{read_only:false; response_revision:26108; number_of_response:1; }","duration":"165.689626ms","start":"2026-05-27T20:35:29.924682Z","end":"2026-05-27T20:35:30.090371Z","steps":["trace[695160133] 'process raft request' (duration: 165.260534ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:35:30.279321Z","caller":"traceutil/trace.go:171","msg":"trace[681859660] transaction","detail":"{read_only:false; response_revision:26112; number_of_response:1; }","duration":"108.611009ms","start":"2026-05-27T20:35:30.170633Z","end":"2026-05-27T20:35:30.279244Z","steps":["trace[681859660] 'process raft request' (duration: 107.668924ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:35:35.20537Z","caller":"traceutil/trace.go:171","msg":"trace[2116115895] transaction","detail":"{read_only:false; response_revision:26143; number_of_response:1; }","duration":"109.798953ms","start":"2026-05-27T20:35:35.095541Z","end":"2026-05-27T20:35:35.205339Z","steps":["trace[2116115895] 'process raft request' (duration: 109.598567ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:35:35.410472Z","caller":"traceutil/trace.go:171","msg":"trace[585236397] transaction","detail":"{read_only:false; response_revision:26144; number_of_response:1; }","duration":"124.183164ms","start":"2026-05-27T20:35:35.286256Z","end":"2026-05-27T20:35:35.410439Z","steps":["trace[585236397] 'process raft request' (duration: 123.968088ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:36:00.284058Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006acb.wal"} {"level":"info","ts":"2026-05-27T20:39:08.071751Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25266} {"level":"info","ts":"2026-05-27T20:39:08.227611Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25266,"took":"145.32198ms","hash":3955258654,"current-db-size-bytes":58064896,"current-db-size":"58 MB","current-db-size-in-use-bytes":38780928,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-05-27T20:39:08.227672Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3955258654,"revision":25266,"compact-revision":21974} {"level":"info","ts":"2026-05-27T20:39:32.574496Z","caller":"traceutil/trace.go:171","msg":"trace[1906422058] transaction","detail":"{read_only:false; response_revision:28522; number_of_response:1; }","duration":"108.490967ms","start":"2026-05-27T20:39:32.465978Z","end":"2026-05-27T20:39:32.574469Z","steps":["trace[1906422058] 'process raft request' (duration: 60.52938ms)","trace[1906422058] 'compare' (duration: 47.117433ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:40:38.839132Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8a0c9a032e61a05a","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-27T20:40:38.845023Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-27T20:40:38.845108Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-27T20:44:08.078279Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28307} {"level":"info","ts":"2026-05-27T20:44:08.213683Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28307,"took":"131.632271ms","hash":2791744854,"current-db-size-bytes":58064896,"current-db-size":"58 MB","current-db-size-in-use-bytes":31477760,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-27T20:44:08.213746Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2791744854,"revision":28307,"compact-revision":25266} {"level":"info","ts":"2026-05-27T20:49:08.085807Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30593} {"level":"info","ts":"2026-05-27T20:49:08.209039Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30593,"took":"117.441026ms","hash":2028358602,"current-db-size-bytes":58064896,"current-db-size":"58 MB","current-db-size-in-use-bytes":30978048,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-27T20:49:08.209105Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2028358602,"revision":30593,"compact-revision":28307} {"level":"info","ts":"2026-05-27T20:54:08.091597Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32848} {"level":"info","ts":"2026-05-27T20:54:08.255632Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32848,"took":"158.716341ms","hash":1158244382,"current-db-size-bytes":58064896,"current-db-size":"58 MB","current-db-size-in-use-bytes":30162944,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-27T20:54:08.255723Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1158244382,"revision":32848,"compact-revision":30593} {"level":"info","ts":"2026-05-27T20:59:08.102696Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35108} {"level":"info","ts":"2026-05-27T20:59:08.250417Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35108,"took":"142.316383ms","hash":3282282186,"current-db-size-bytes":58535936,"current-db-size":"58 MB","current-db-size-in-use-bytes":31526912,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-27T20:59:08.250495Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3282282186,"revision":35108,"compact-revision":32848} {"level":"info","ts":"2026-05-27T21:02:00.814432Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8a0c9a032e61a05a","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-27T21:02:00.81968Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-27T21:02:00.819967Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-27T21:04:08.110095Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37376} {"level":"info","ts":"2026-05-27T21:04:08.235147Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37376,"took":"117.407342ms","hash":3432535296,"current-db-size-bytes":59379712,"current-db-size":"59 MB","current-db-size-in-use-bytes":32063488,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-27T21:04:08.235253Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3432535296,"revision":37376,"compact-revision":35108}