{"level":"warn","ts":"2026-05-30T09:48:05.501401Z","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-30T09:48:05.501528Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140: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-30T09:48:05.501611Z","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-30T09:48:05.501631Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-05-30T09:48:05.501656Z","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-30T09:48:05.502539Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-05-30T09:48:05.502639Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140: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-30T09:48:05.541877Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"38.981926ms"} {"level":"info","ts":"2026-05-30T09:48:05.560213Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-05-30T09:48:05.560302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-05-30T09:48:05.560327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-05-30T09:48:05.560345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-30T09:48:05.560361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-05-30T09:48:05.560409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-05-30T09:48:05.566548Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-30T09:48:05.56781Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-30T09:48:05.568813Z","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-30T09:48:05.571014Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-30T09:48:05.571336Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-30T09:48:05.571488Z","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-30T09:48:05.571584Z","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-30T09:48:05.571594Z","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-30T09:48:05.572862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-05-30T09:48:05.573133Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-05-30T09:48:05.57909Z","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-30T09:48:05.57928Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-05-30T09:48:05.579374Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-05-30T09:48:05.579594Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-30T09:48:05.579635Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-30T09:48:05.663695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-05-30T09:48:05.663794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-05-30T09:48:05.663817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-05-30T09:48:05.663834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-05-30T09:48:05.663842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-05-30T09:48:05.663853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-05-30T09:48:05.663863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-05-30T09:48:05.671833Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:48:05.678311Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-05-30T09:48:05.678633Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T09:48:05.678902Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:48:05.67907Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T09:48:05.679083Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:48:05.679161Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:48:05.679174Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-30T09:48:05.679241Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-30T09:48:05.680582Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-30T09:48:05.685695Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-05-30T09:48:23.163391Z","caller":"traceutil/trace.go:171","msg":"trace[1807459972] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:386; }","duration":"142.62383ms","start":"2026-05-30T09:48:23.020738Z","end":"2026-05-30T09:48:23.163362Z","steps":["trace[1807459972] 'read index received' (duration: 141.760892ms)","trace[1807459972] 'applied index is now lower than readState.Index' (duration: 861.788µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:48:23.163459Z","caller":"traceutil/trace.go:171","msg":"trace[1132367102] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"159.514873ms","start":"2026-05-30T09:48:23.003923Z","end":"2026-05-30T09:48:23.163438Z","steps":["trace[1132367102] 'process raft request' (duration: 158.659575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:23.163584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.827814ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:48:23.164086Z","caller":"traceutil/trace.go:171","msg":"trace[1218210902] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:372; }","duration":"143.345415ms","start":"2026-05-30T09:48:23.020729Z","end":"2026-05-30T09:48:23.164075Z","steps":["trace[1218210902] 'agreement among raft nodes before linearized reading' (duration: 142.750813ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:23.403719Z","caller":"traceutil/trace.go:171","msg":"trace[265449726] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"226.572281ms","start":"2026-05-30T09:48:23.177122Z","end":"2026-05-30T09:48:23.403694Z","steps":["trace[265449726] 'process raft request' (duration: 222.32005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:48.893862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.652721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:48:48.893996Z","caller":"traceutil/trace.go:171","msg":"trace[56589422] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"157.187348ms","start":"2026-05-30T09:48:48.736776Z","end":"2026-05-30T09:48:48.893964Z","steps":["trace[56589422] 'process raft request' (duration: 17.637398ms)","trace[56589422] 'compare' (duration: 138.497368ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:48:49.436744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.317538ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:48:49.436833Z","caller":"traceutil/trace.go:171","msg":"trace[1130396113] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"334.714282ms","start":"2026-05-30T09:48:49.102104Z","end":"2026-05-30T09:48:49.436819Z","steps":["trace[1130396113] 'process raft request' (duration: 229.145809ms)","trace[1130396113] 'compare' (duration: 104.884499ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:48:49.436872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:49.102085Z","time spent":"334.770573ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:48:49.612059Z","caller":"traceutil/trace.go:171","msg":"trace[1231604994] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"309.971492ms","start":"2026-05-30T09:48:49.302046Z","end":"2026-05-30T09:48:49.612018Z","steps":["trace[1231604994] 'process raft request' (duration: 309.489021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:49.612247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:48:49.302015Z","time spent":"310.133775ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036153,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:50:22.046124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.010751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:50:22.046246Z","caller":"traceutil/trace.go:171","msg":"trace[206324362] linearizableReadLoop","detail":"{readStateIndex:1899; appliedIndex:1898; }","duration":"196.643333ms","start":"2026-05-30T09:50:21.849585Z","end":"2026-05-30T09:50:22.046229Z","steps":["trace[206324362] 'read index received' (duration: 13.334516ms)","trace[206324362] 'applied index is now lower than readState.Index' (duration: 183.307437ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:50:22.046388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.813086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ae5c39cd-d9e9-4350-b32f-5261c97d9c04\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-30T09:50:22.046416Z","caller":"traceutil/trace.go:171","msg":"trace[1839081215] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ae5c39cd-d9e9-4350-b32f-5261c97d9c04; range_end:; response_count:1; response_revision:1826; }","duration":"196.887038ms","start":"2026-05-30T09:50:21.849519Z","end":"2026-05-30T09:50:22.046406Z","steps":["trace[1839081215] 'agreement among raft nodes before linearized reading' (duration: 196.755145ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:22.046404Z","caller":"traceutil/trace.go:171","msg":"trace[406284920] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"278.066637ms","start":"2026-05-30T09:50:21.768305Z","end":"2026-05-30T09:50:22.046371Z","steps":["trace[406284920] 'process raft request' (duration: 94.705399ms)","trace[406284920] 'compare' (duration: 182.851547ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:52:35.086458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.259405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-30T09:52:35.086538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.35245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-30T09:52:35.086563Z","caller":"traceutil/trace.go:171","msg":"trace[1246753312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2787; }","duration":"210.410128ms","start":"2026-05-30T09:52:34.876131Z","end":"2026-05-30T09:52:35.086541Z","steps":["trace[1246753312] 'range keys from in-memory index tree' (duration: 210.082321ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:35.086606Z","caller":"traceutil/trace.go:171","msg":"trace[759770344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2787; }","duration":"205.482473ms","start":"2026-05-30T09:52:34.881107Z","end":"2026-05-30T09:52:35.08659Z","steps":["trace[759770344] 'range keys from in-memory index tree' (duration: 205.150585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:35.086601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.990327ms","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-30T09:52:35.086682Z","caller":"traceutil/trace.go:171","msg":"trace[2002704778] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2787; }","duration":"227.119921ms","start":"2026-05-30T09:52:34.859544Z","end":"2026-05-30T09:52:35.086664Z","steps":["trace[2002704778] 'range keys from in-memory index tree' (duration: 226.835484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:35.087002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.129601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-30T09:52:35.087159Z","caller":"traceutil/trace.go:171","msg":"trace[1058667977] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2787; }","duration":"221.278734ms","start":"2026-05-30T09:52:34.865818Z","end":"2026-05-30T09:52:35.087096Z","steps":["trace[1058667977] 'range keys from in-memory index tree' (duration: 220.624181ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:35.238797Z","caller":"traceutil/trace.go:171","msg":"trace[851753395] transaction","detail":"{read_only:false; response_revision:2788; number_of_response:1; }","duration":"147.65727ms","start":"2026-05-30T09:52:35.090875Z","end":"2026-05-30T09:52:35.238532Z","steps":["trace[851753395] 'process raft request' (duration: 147.495356ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:35.267828Z","caller":"traceutil/trace.go:171","msg":"trace[446382000] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"176.77457ms","start":"2026-05-30T09:52:35.09104Z","end":"2026-05-30T09:52:35.267815Z","steps":["trace[446382000] 'process raft request' (duration: 176.544655ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:35.268019Z","caller":"traceutil/trace.go:171","msg":"trace[1122786673] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"176.885153ms","start":"2026-05-30T09:52:35.091075Z","end":"2026-05-30T09:52:35.26796Z","steps":["trace[1122786673] 'process raft request' (duration: 176.662547ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:35.267842Z","caller":"traceutil/trace.go:171","msg":"trace[330699555] transaction","detail":"{read_only:false; response_revision:2790; number_of_response:1; }","duration":"176.761309ms","start":"2026-05-30T09:52:35.091036Z","end":"2026-05-30T09:52:35.267798Z","steps":["trace[330699555] 'process raft request' (duration: 176.662777ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:56:26.777572Z","caller":"traceutil/trace.go:171","msg":"trace[227601851] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"100.904156ms","start":"2026-05-30T09:56:26.676623Z","end":"2026-05-30T09:56:26.777528Z","steps":["trace[227601851] 'process raft request' (duration: 63.479856ms)","trace[227601851] 'compare' (duration: 37.261857ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:56:30.723628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.204448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:56:30.723735Z","caller":"traceutil/trace.go:171","msg":"trace[833066371] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:4953; }","duration":"248.366591ms","start":"2026-05-30T09:56:30.475348Z","end":"2026-05-30T09:56:30.723715Z","steps":["trace[833066371] 'count revisions from in-memory index tree' (duration: 248.139906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:56:30.723771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.434749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:56:30.723786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.316472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-30T09:56:30.723842Z","caller":"traceutil/trace.go:171","msg":"trace[1490521759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4953; }","duration":"198.559921ms","start":"2026-05-30T09:56:30.525267Z","end":"2026-05-30T09:56:30.723827Z","steps":["trace[1490521759] 'range keys from in-memory index tree' (duration: 198.349227ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:56:30.72387Z","caller":"traceutil/trace.go:171","msg":"trace[1766020601] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4953; }","duration":"267.426545ms","start":"2026-05-30T09:56:30.45642Z","end":"2026-05-30T09:56:30.723846Z","steps":["trace[1766020601] 'range keys from in-memory index tree' (duration: 267.141518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:56:30.723853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.370363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-30T09:56:30.72393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.700864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-30T09:56:30.724032Z","caller":"traceutil/trace.go:171","msg":"trace[693430689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4953; }","duration":"270.854348ms","start":"2026-05-30T09:56:30.453152Z","end":"2026-05-30T09:56:30.724007Z","steps":["trace[693430689] 'range keys from in-memory index tree' (duration: 270.523441ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:56:30.723947Z","caller":"traceutil/trace.go:171","msg":"trace[1282652642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4953; }","duration":"267.507286ms","start":"2026-05-30T09:56:30.45642Z","end":"2026-05-30T09:56:30.723927Z","steps":["trace[1282652642] 'range keys from in-memory index tree' (duration: 267.161139ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:56:30.766724Z","caller":"traceutil/trace.go:171","msg":"trace[2068800562] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"119.965299ms","start":"2026-05-30T09:56:30.646727Z","end":"2026-05-30T09:56:30.766693Z","steps":["trace[2068800562] 'process raft request' (duration: 119.772265ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:56:30.79508Z","caller":"traceutil/trace.go:171","msg":"trace[728292634] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"113.869276ms","start":"2026-05-30T09:56:30.681186Z","end":"2026-05-30T09:56:30.795056Z","steps":["trace[728292634] 'process raft request' (duration: 113.432267ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:56:43.340615Z","caller":"traceutil/trace.go:171","msg":"trace[1971999418] transaction","detail":"{read_only:false; response_revision:5216; number_of_response:1; }","duration":"115.282178ms","start":"2026-05-30T09:56:43.225304Z","end":"2026-05-30T09:56:43.340586Z","steps":["trace[1971999418] 'process raft request' (duration: 35.58455ms)","trace[1971999418] 'compare' (duration: 79.459241ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:56:43.925493Z","caller":"traceutil/trace.go:171","msg":"trace[1119182432] transaction","detail":"{read_only:false; response_revision:5221; number_of_response:1; }","duration":"286.940206ms","start":"2026-05-30T09:56:43.638529Z","end":"2026-05-30T09:56:43.925469Z","steps":["trace[1119182432] 'process raft request' (duration: 286.898155ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:56:43.925635Z","caller":"traceutil/trace.go:171","msg":"trace[751498346] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"288.519171ms","start":"2026-05-30T09:56:43.637087Z","end":"2026-05-30T09:56:43.925606Z","steps":["trace[751498346] 'process raft request' (duration: 287.798736ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:56:43.925834Z","caller":"traceutil/trace.go:171","msg":"trace[2060971573] transaction","detail":"{read_only:false; response_revision:5220; number_of_response:1; }","duration":"287.670252ms","start":"2026-05-30T09:56:43.638113Z","end":"2026-05-30T09:56:43.925784Z","steps":["trace[2060971573] 'process raft request' (duration: 287.246623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:56:44.227189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.117814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-30T09:56:44.227267Z","caller":"traceutil/trace.go:171","msg":"trace[727755890] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5221; }","duration":"181.227196ms","start":"2026-05-30T09:56:44.046021Z","end":"2026-05-30T09:56:44.227248Z","steps":["trace[727755890] 'range keys from in-memory index tree' (duration: 180.899719ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:58:05.977952Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2962} {"level":"info","ts":"2026-05-30T09:58:06.050336Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2962,"took":"70.675629ms","hash":127338640,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":33988608,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-30T09:58:06.050429Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":127338640,"revision":2962,"compact-revision":-1} {"level":"info","ts":"2026-05-30T10:02:46.363572Z","caller":"traceutil/trace.go:171","msg":"trace[589025242] transaction","detail":"{read_only:false; response_revision:8148; number_of_response:1; }","duration":"192.699014ms","start":"2026-05-30T10:02:46.170852Z","end":"2026-05-30T10:02:46.363551Z","steps":["trace[589025242] 'process raft request' (duration: 192.563731ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:02:46.531252Z","caller":"traceutil/trace.go:171","msg":"trace[1205414786] linearizableReadLoop","detail":"{readStateIndex:8435; appliedIndex:8434; }","duration":"300.674832ms","start":"2026-05-30T10:02:46.230549Z","end":"2026-05-30T10:02:46.531224Z","steps":["trace[1205414786] 'read index received' (duration: 133.096362ms)","trace[1205414786] 'applied index is now lower than readState.Index' (duration: 167.57736ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:02:46.531298Z","caller":"traceutil/trace.go:171","msg":"trace[1875600446] transaction","detail":"{read_only:false; response_revision:8149; number_of_response:1; }","duration":"358.060976ms","start":"2026-05-30T10:02:46.173216Z","end":"2026-05-30T10:02:46.531277Z","steps":["trace[1875600446] 'process raft request' (duration: 356.909071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:02:46.531407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:02:46.173192Z","time spent":"358.154697ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T10:02:46.531429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.880176ms","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-30T10:02:46.531465Z","caller":"traceutil/trace.go:171","msg":"trace[2100241833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8149; }","duration":"300.964098ms","start":"2026-05-30T10:02:46.23049Z","end":"2026-05-30T10:02:46.531455Z","steps":["trace[2100241833] 'agreement among raft nodes before linearized reading' (duration: 300.857645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:02:46.531487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:02:46.230481Z","time spent":"301.001749ms","remote":"127.0.0.1:48486","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-30T10:02:59.04122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.651448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T10:02:59.041329Z","caller":"traceutil/trace.go:171","msg":"trace[1953632647] transaction","detail":"{read_only:false; response_revision:8215; number_of_response:1; }","duration":"285.090245ms","start":"2026-05-30T10:02:58.756228Z","end":"2026-05-30T10:02:59.041319Z","steps":["trace[1953632647] 'process raft request' (duration: 180.291705ms)","trace[1953632647] 'compare' (duration: 104.556595ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:02:59.041521Z","caller":"traceutil/trace.go:171","msg":"trace[1336837431] transaction","detail":"{read_only:false; response_revision:8216; number_of_response:1; }","duration":"284.655515ms","start":"2026-05-30T10:02:58.756859Z","end":"2026-05-30T10:02:59.041515Z","steps":["trace[1336837431] 'process raft request' (duration: 284.435751ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:02:59.041537Z","caller":"traceutil/trace.go:171","msg":"trace[1199978979] linearizableReadLoop","detail":"{readStateIndex:8504; appliedIndex:8502; }","duration":"157.277887ms","start":"2026-05-30T10:02:58.88422Z","end":"2026-05-30T10:02:59.041498Z","steps":["trace[1199978979] 'read index received' (duration: 52.357364ms)","trace[1199978979] 'applied index is now lower than readState.Index' (duration: 104.919003ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:02:59.042307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.067333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T10:02:59.042386Z","caller":"traceutil/trace.go:171","msg":"trace[1169793119] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:8216; }","duration":"158.194136ms","start":"2026-05-30T10:02:58.884174Z","end":"2026-05-30T10:02:59.042368Z","steps":["trace[1169793119] 'agreement among raft nodes before linearized reading' (duration: 157.42549ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:03:00.234823Z","caller":"traceutil/trace.go:171","msg":"trace[1912432174] transaction","detail":"{read_only:false; response_revision:8221; number_of_response:1; }","duration":"121.131053ms","start":"2026-05-30T10:03:00.113664Z","end":"2026-05-30T10:03:00.234795Z","steps":["trace[1912432174] 'process raft request' (duration: 120.943669ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:03:05.984711Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6065} {"level":"info","ts":"2026-05-30T10:03:06.086999Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6065,"took":"100.092398ms","hash":2046815924,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":23318528,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-30T10:03:06.087074Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2046815924,"revision":6065,"compact-revision":2962} {"level":"info","ts":"2026-05-30T10:03:24.778935Z","caller":"traceutil/trace.go:171","msg":"trace[629417477] transaction","detail":"{read_only:false; response_revision:8407; number_of_response:1; }","duration":"100.849684ms","start":"2026-05-30T10:03:24.678062Z","end":"2026-05-30T10:03:24.778911Z","steps":["trace[629417477] 'process raft request' (duration: 100.65281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:03:38.745252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.861787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T10:03:38.745346Z","caller":"traceutil/trace.go:171","msg":"trace[1188826121] linearizableReadLoop","detail":"{readStateIndex:8823; appliedIndex:8822; }","duration":"145.266266ms","start":"2026-05-30T10:03:38.600064Z","end":"2026-05-30T10:03:38.74533Z","steps":["trace[1188826121] 'read index received' (duration: 19.219766ms)","trace[1188826121] 'applied index is now lower than readState.Index' (duration: 126.0455ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:03:38.745418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.349658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T10:03:38.745459Z","caller":"traceutil/trace.go:171","msg":"trace[1146280838] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:8521; }","duration":"145.41907ms","start":"2026-05-30T10:03:38.600016Z","end":"2026-05-30T10:03:38.745435Z","steps":["trace[1146280838] 'agreement among raft nodes before linearized reading' (duration: 145.355338ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:03:38.745664Z","caller":"traceutil/trace.go:171","msg":"trace[1380842514] transaction","detail":"{read_only:false; response_revision:8521; number_of_response:1; }","duration":"175.747387ms","start":"2026-05-30T10:03:38.569901Z","end":"2026-05-30T10:03:38.745648Z","steps":["trace[1380842514] 'process raft request' (duration: 49.399941ms)","trace[1380842514] 'compare' (duration: 125.707233ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:03:39.38443Z","caller":"traceutil/trace.go:171","msg":"trace[549145606] linearizableReadLoop","detail":"{readStateIndex:8832; appliedIndex:8830; }","duration":"137.655681ms","start":"2026-05-30T10:03:39.246746Z","end":"2026-05-30T10:03:39.384402Z","steps":["trace[549145606] 'read index received' (duration: 13.080633ms)","trace[549145606] 'applied index is now lower than readState.Index' (duration: 124.573948ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:03:39.384553Z","caller":"traceutil/trace.go:171","msg":"trace[444662339] transaction","detail":"{read_only:false; response_revision:8529; number_of_response:1; }","duration":"155.791664ms","start":"2026-05-30T10:03:39.228713Z","end":"2026-05-30T10:03:39.384505Z","steps":["trace[444662339] 'process raft request' (duration: 110.33778ms)","trace[444662339] 'compare' (duration: 45.23019ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:03:39.384696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.924978ms","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-30T10:03:39.384802Z","caller":"traceutil/trace.go:171","msg":"trace[1385157016] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8529; }","duration":"138.05327ms","start":"2026-05-30T10:03:39.24673Z","end":"2026-05-30T10:03:39.384783Z","steps":["trace[1385157016] 'agreement among raft nodes before linearized reading' (duration: 137.764994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:03:39.725763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.475812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T10:03:39.725825Z","caller":"traceutil/trace.go:171","msg":"trace[185575865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8530; }","duration":"200.575475ms","start":"2026-05-30T10:03:39.525234Z","end":"2026-05-30T10:03:39.725809Z","steps":["trace[185575865] 'range keys from in-memory index tree' (duration: 200.407301ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:03:39.743335Z","caller":"traceutil/trace.go:171","msg":"trace[1926197561] transaction","detail":"{read_only:false; response_revision:8531; number_of_response:1; }","duration":"189.557196ms","start":"2026-05-30T10:03:39.553753Z","end":"2026-05-30T10:03:39.74331Z","steps":["trace[1926197561] 'process raft request' (duration: 189.396592ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:04:27.452696Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000244a.wal"} {"level":"info","ts":"2026-05-30T10:05:47.559334Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"35b2766da46a76bb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-30T10:05:47.564968Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-30T10:05:47.56519Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-30T10:08:05.990687Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8260} {"level":"info","ts":"2026-05-30T10:08:06.072072Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8260,"took":"79.008707ms","hash":2184865820,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":26677248,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-30T10:08:06.072154Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2184865820,"revision":8260,"compact-revision":6065} {"level":"info","ts":"2026-05-30T10:08:24.998058Z","caller":"traceutil/trace.go:171","msg":"trace[1276835294] linearizableReadLoop","detail":"{readStateIndex:11423; appliedIndex:11422; }","duration":"149.730315ms","start":"2026-05-30T10:08:24.848293Z","end":"2026-05-30T10:08:24.998023Z","steps":["trace[1276835294] 'read index received' (duration: 130.275705ms)","trace[1276835294] 'applied index is now lower than readState.Index' (duration: 19.45356ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:08:24.998294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.98865ms","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-30T10:08:24.998336Z","caller":"traceutil/trace.go:171","msg":"trace[2146286019] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11047; }","duration":"150.086602ms","start":"2026-05-30T10:08:24.848237Z","end":"2026-05-30T10:08:24.998323Z","steps":["trace[2146286019] 'agreement among raft nodes before linearized reading' (duration: 149.893688ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:08:24.998469Z","caller":"traceutil/trace.go:171","msg":"trace[1872253188] transaction","detail":"{read_only:false; response_revision:11047; number_of_response:1; }","duration":"181.169725ms","start":"2026-05-30T10:08:24.817267Z","end":"2026-05-30T10:08:24.998436Z","steps":["trace[1872253188] 'process raft request' (duration: 161.422038ms)","trace[1872253188] 'compare' (duration: 18.864308ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:13:05.996456Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10941} {"level":"info","ts":"2026-05-30T10:13:06.084398Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10941,"took":"85.000516ms","hash":975924289,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":27860992,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-30T10:13:06.084461Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":975924289,"revision":10941,"compact-revision":8260} {"level":"warn","ts":"2026-05-30T10:14:50.592001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.27058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-hm9mv\" ","response":"range_response_count:1 size:12599"} {"level":"info","ts":"2026-05-30T10:14:50.59212Z","caller":"traceutil/trace.go:171","msg":"trace[116885736] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-hm9mv; range_end:; response_count:1; response_revision:13643; }","duration":"278.445844ms","start":"2026-05-30T10:14:50.31365Z","end":"2026-05-30T10:14:50.592096Z","steps":["trace[116885736] 'range keys from in-memory index tree' (duration: 278.007194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:14:50.592982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.928815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:86 size:929847"} {"level":"info","ts":"2026-05-30T10:14:50.593103Z","caller":"traceutil/trace.go:171","msg":"trace[1336613100] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:86; response_revision:13643; }","duration":"437.099359ms","start":"2026-05-30T10:14:50.155978Z","end":"2026-05-30T10:14:50.593078Z","steps":["trace[1336613100] 'range keys from in-memory index tree' (duration: 436.008136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:14:50.593173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:14:50.155962Z","time spent":"437.19285ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":86,"response size":929870,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"info","ts":"2026-05-30T10:14:50.848817Z","caller":"traceutil/trace.go:171","msg":"trace[1654478135] linearizableReadLoop","detail":"{readStateIndex:14111; appliedIndex:14110; }","duration":"322.77862ms","start":"2026-05-30T10:14:50.526006Z","end":"2026-05-30T10:14:50.848785Z","steps":["trace[1654478135] 'read index received' (duration: 322.711219ms)","trace[1654478135] 'applied index is now lower than readState.Index' (duration: 65.861µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:14:50.849004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.980775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T10:14:50.84907Z","caller":"traceutil/trace.go:171","msg":"trace[625638409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13644; }","duration":"323.137099ms","start":"2026-05-30T10:14:50.525916Z","end":"2026-05-30T10:14:50.849053Z","steps":["trace[625638409] 'agreement among raft nodes before linearized reading' (duration: 323.015986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:14:50.849115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:14:50.525895Z","time spent":"323.21132ms","remote":"127.0.0.1:48230","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-30T10:14:50.848949Z","caller":"traceutil/trace.go:171","msg":"trace[1231491967] transaction","detail":"{read_only:false; response_revision:13644; number_of_response:1; }","duration":"484.803299ms","start":"2026-05-30T10:14:50.364112Z","end":"2026-05-30T10:14:50.848915Z","steps":["trace[1231491967] 'process raft request' (duration: 484.490353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:14:50.849381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:14:50.364083Z","time spent":"485.150927ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:14:51.01025Z","caller":"traceutil/trace.go:171","msg":"trace[1167852918] transaction","detail":"{read_only:false; response_revision:13645; number_of_response:1; }","duration":"406.918507ms","start":"2026-05-30T10:14:50.603301Z","end":"2026-05-30T10:14:51.010219Z","steps":["trace[1167852918] 'process raft request' (duration: 406.688812ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:14:51.010418Z","caller":"traceutil/trace.go:171","msg":"trace[1762565741] linearizableReadLoop","detail":"{readStateIndex:14113; appliedIndex:14111; }","duration":"161.490628ms","start":"2026-05-30T10:14:50.848892Z","end":"2026-05-30T10:14:51.010383Z","steps":["trace[1762565741] 'read index received' (duration: 161.030628ms)","trace[1762565741] 'applied index is now lower than readState.Index' (duration: 458.149µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:14:51.010409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:14:50.603277Z","time spent":"407.05038ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12713,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:14:51.010469Z","caller":"traceutil/trace.go:171","msg":"trace[1384432856] transaction","detail":"{read_only:false; response_revision:13646; number_of_response:1; }","duration":"191.426064ms","start":"2026-05-30T10:14:50.819015Z","end":"2026-05-30T10:14:51.010441Z","steps":["trace[1384432856] 'process raft request' (duration: 191.135408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:14:51.010646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.432444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T10:14:51.010701Z","caller":"traceutil/trace.go:171","msg":"trace[229574719] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13646; }","duration":"160.545497ms","start":"2026-05-30T10:14:50.850138Z","end":"2026-05-30T10:14:51.010684Z","steps":["trace[229574719] 'agreement among raft nodes before linearized reading' (duration: 160.432705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:14:51.010746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.540576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-30T10:14:51.010756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.199959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-30T10:14:51.010855Z","caller":"traceutil/trace.go:171","msg":"trace[991871741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13646; }","duration":"436.366552ms","start":"2026-05-30T10:14:50.57447Z","end":"2026-05-30T10:14:51.010837Z","steps":["trace[991871741] 'agreement among raft nodes before linearized reading' (duration: 436.079806ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:14:51.010879Z","caller":"traceutil/trace.go:171","msg":"trace[1823059168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13646; }","duration":"366.707559ms","start":"2026-05-30T10:14:50.644108Z","end":"2026-05-30T10:14:51.010816Z","steps":["trace[1823059168] 'agreement among raft nodes before linearized reading' (duration: 366.406232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:14:51.010909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:14:50.57444Z","time spent":"436.459975ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T10:14:51.010928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:14:50.644079Z","time spent":"366.840172ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-30T10:18:06.006298Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12973} {"level":"info","ts":"2026-05-30T10:18:06.089898Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12973,"took":"81.266635ms","hash":3354972367,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":31047680,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-30T10:18:06.089967Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3354972367,"revision":12973,"compact-revision":10941} {"level":"info","ts":"2026-05-30T10:22:11.979779Z","caller":"traceutil/trace.go:171","msg":"trace[913255572] transaction","detail":"{read_only:false; response_revision:17424; number_of_response:1; }","duration":"179.300742ms","start":"2026-05-30T10:22:11.800459Z","end":"2026-05-30T10:22:11.97976Z","steps":["trace[913255572] 'process raft request' (duration: 171.554584ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:22:11.980067Z","caller":"traceutil/trace.go:171","msg":"trace[307959032] transaction","detail":"{read_only:false; response_revision:17425; number_of_response:1; }","duration":"179.083407ms","start":"2026-05-30T10:22:11.800955Z","end":"2026-05-30T10:22:11.980038Z","steps":["trace[307959032] 'process raft request' (duration: 178.735699ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:22:11.980131Z","caller":"traceutil/trace.go:171","msg":"trace[2123549000] linearizableReadLoop","detail":"{readStateIndex:18010; appliedIndex:18006; }","duration":"170.647725ms","start":"2026-05-30T10:22:11.809456Z","end":"2026-05-30T10:22:11.980104Z","steps":["trace[2123549000] 'read index received' (duration: 22.67182ms)","trace[2123549000] 'applied index is now lower than readState.Index' (duration: 147.974735ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:22:11.980153Z","caller":"traceutil/trace.go:171","msg":"trace[1021417239] transaction","detail":"{read_only:false; response_revision:17426; number_of_response:1; }","duration":"178.955135ms","start":"2026-05-30T10:22:11.801181Z","end":"2026-05-30T10:22:11.980136Z","steps":["trace[1021417239] 'process raft request' (duration: 178.701909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:22:11.980426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.950832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-05-30T10:22:11.981468Z","caller":"traceutil/trace.go:171","msg":"trace[766123102] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:17426; }","duration":"172.021475ms","start":"2026-05-30T10:22:11.809424Z","end":"2026-05-30T10:22:11.981446Z","steps":["trace[766123102] 'agreement among raft nodes before linearized reading' (duration: 170.759637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:22:18.024784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.508725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-kq9ps\" ","response":"range_response_count:1 size:20866"} {"level":"info","ts":"2026-05-30T10:22:18.024854Z","caller":"traceutil/trace.go:171","msg":"trace[1850333757] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-kq9ps; range_end:; response_count:1; response_revision:17466; }","duration":"178.649678ms","start":"2026-05-30T10:22:17.846189Z","end":"2026-05-30T10:22:18.024838Z","steps":["trace[1850333757] 'range keys from in-memory index tree' (duration: 178.26048ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:22:18.500264Z","caller":"traceutil/trace.go:171","msg":"trace[1910299870] linearizableReadLoop","detail":"{readStateIndex:18052; appliedIndex:18051; }","duration":"211.373365ms","start":"2026-05-30T10:22:18.288868Z","end":"2026-05-30T10:22:18.500241Z","steps":["trace[1910299870] 'read index received' (duration: 211.11916ms)","trace[1910299870] 'applied index is now lower than readState.Index' (duration: 252.795µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:22:18.5004Z","caller":"traceutil/trace.go:171","msg":"trace[866397358] transaction","detail":"{read_only:false; response_revision:17467; number_of_response:1; }","duration":"456.428377ms","start":"2026-05-30T10:22:18.043932Z","end":"2026-05-30T10:22:18.50036Z","steps":["trace[866397358] 'process raft request' (duration: 456.142441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:22:18.500563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:22:18.0439Z","time spent":"456.578559ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20981,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T10:22:18.500632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.775714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-dcfh2\" ","response":"range_response_count:1 size:22126"} {"level":"warn","ts":"2026-05-30T10:22:18.500666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.89573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-30T10:22:18.500444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.548359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-30T10:22:18.500711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.687375ms","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-30T10:22:18.500751Z","caller":"traceutil/trace.go:171","msg":"trace[1781711500] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:17467; }","duration":"211.903267ms","start":"2026-05-30T10:22:18.288833Z","end":"2026-05-30T10:22:18.500736Z","steps":["trace[1781711500] 'agreement among raft nodes before linearized reading' (duration: 211.552449ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:22:18.500804Z","caller":"traceutil/trace.go:171","msg":"trace[1628466367] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17467; }","duration":"125.829528ms","start":"2026-05-30T10:22:18.374955Z","end":"2026-05-30T10:22:18.500785Z","steps":["trace[1628466367] 'agreement among raft nodes before linearized reading' (duration: 125.567132ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:22:18.500735Z","caller":"traceutil/trace.go:171","msg":"trace[367459578] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-dcfh2; range_end:; response_count:1; response_revision:17467; }","duration":"161.940047ms","start":"2026-05-30T10:22:18.338773Z","end":"2026-05-30T10:22:18.500713Z","steps":["trace[367459578] 'agreement among raft nodes before linearized reading' (duration: 161.655061ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:22:18.500742Z","caller":"traceutil/trace.go:171","msg":"trace[2076668395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17467; }","duration":"119.992752ms","start":"2026-05-30T10:22:18.380735Z","end":"2026-05-30T10:22:18.500728Z","steps":["trace[2076668395] 'agreement among raft nodes before linearized reading' (duration: 119.752976ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:23:06.021762Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15462} {"level":"info","ts":"2026-05-30T10:23:06.149157Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15462,"took":"124.327014ms","hash":2882674403,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":33648640,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-30T10:23:06.149212Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2882674403,"revision":15462,"compact-revision":12973} {"level":"info","ts":"2026-05-30T10:25:58.888776Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"35b2766da46a76bb","local-member-applied-index":20003,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-30T10:25:58.897474Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-05-30T10:25:58.897573Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"warn","ts":"2026-05-30T10:27:09.984064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.907727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-30T10:27:09.984208Z","caller":"traceutil/trace.go:171","msg":"trace[1831619018] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:19776; }","duration":"188.102371ms","start":"2026-05-30T10:27:09.796083Z","end":"2026-05-30T10:27:09.984186Z","steps":["trace[1831619018] 'count revisions from in-memory index tree' (duration: 187.779643ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:27:25.470995Z","caller":"traceutil/trace.go:171","msg":"trace[469464384] transaction","detail":"{read_only:false; response_revision:19853; number_of_response:1; }","duration":"152.002983ms","start":"2026-05-30T10:27:25.318969Z","end":"2026-05-30T10:27:25.470972Z","steps":["trace[469464384] 'process raft request' (duration: 151.86281ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:27:25.485289Z","caller":"traceutil/trace.go:171","msg":"trace[1704427478] transaction","detail":"{read_only:false; response_revision:19855; number_of_response:1; }","duration":"163.96036ms","start":"2026-05-30T10:27:25.321303Z","end":"2026-05-30T10:27:25.485264Z","steps":["trace[1704427478] 'process raft request' (duration: 163.909099ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:27:25.485827Z","caller":"traceutil/trace.go:171","msg":"trace[1217774073] transaction","detail":"{read_only:false; response_revision:19854; number_of_response:1; }","duration":"166.441214ms","start":"2026-05-30T10:27:25.319372Z","end":"2026-05-30T10:27:25.485813Z","steps":["trace[1217774073] 'process raft request' (duration: 165.666398ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:27:31.766911Z","caller":"traceutil/trace.go:171","msg":"trace[990348888] transaction","detail":"{read_only:false; response_revision:19887; number_of_response:1; }","duration":"169.627222ms","start":"2026-05-30T10:27:31.597253Z","end":"2026-05-30T10:27:31.76688Z","steps":["trace[990348888] 'process raft request' (duration: 169.439328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:27:32.051785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.09712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-30T10:27:32.052179Z","caller":"traceutil/trace.go:171","msg":"trace[1872380175] transaction","detail":"{read_only:false; response_revision:19888; number_of_response:1; }","duration":"453.706135ms","start":"2026-05-30T10:27:31.598437Z","end":"2026-05-30T10:27:32.052143Z","steps":["trace[1872380175] 'process raft request' (duration: 308.350817ms)","trace[1872380175] 'compare' (duration: 143.971408ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:27:32.05226Z","caller":"traceutil/trace.go:171","msg":"trace[1538209862] transaction","detail":"{read_only:false; response_revision:19889; number_of_response:1; }","duration":"323.629227ms","start":"2026-05-30T10:27:31.728603Z","end":"2026-05-30T10:27:32.052232Z","steps":["trace[1538209862] 'process raft request' (duration: 323.27466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:27:32.052337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:27:31.598411Z","time spent":"453.843189ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T10:27:32.05238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:27:31.728579Z","time spent":"323.73822ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:27:32.306369Z","caller":"traceutil/trace.go:171","msg":"trace[143386911] linearizableReadLoop","detail":"{readStateIndex:20555; appliedIndex:20554; }","duration":"153.994885ms","start":"2026-05-30T10:27:32.152356Z","end":"2026-05-30T10:27:32.306351Z","steps":["trace[143386911] 'read index received' (duration: 76.917991ms)","trace[143386911] 'applied index is now lower than readState.Index' (duration: 77.076014ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:27:32.306474Z","caller":"traceutil/trace.go:171","msg":"trace[74783196] transaction","detail":"{read_only:false; response_revision:19890; number_of_response:1; }","duration":"188.05941ms","start":"2026-05-30T10:27:32.118402Z","end":"2026-05-30T10:27:32.306461Z","steps":["trace[74783196] 'process raft request' (duration: 110.895274ms)","trace[74783196] 'compare' (duration: 76.87609ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:27:32.306713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.338142ms","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-30T10:27:32.306754Z","caller":"traceutil/trace.go:171","msg":"trace[1814322594] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19891; }","duration":"154.436754ms","start":"2026-05-30T10:27:32.152307Z","end":"2026-05-30T10:27:32.306743Z","steps":["trace[1814322594] 'agreement among raft nodes before linearized reading' (duration: 154.278841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:27:32.307052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.974605ms","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-30T10:27:32.307086Z","caller":"traceutil/trace.go:171","msg":"trace[877843435] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19891; }","duration":"154.046995ms","start":"2026-05-30T10:27:32.15303Z","end":"2026-05-30T10:27:32.307078Z","steps":["trace[877843435] 'agreement among raft nodes before linearized reading' (duration: 153.954074ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:28:06.033891Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18030} {"level":"info","ts":"2026-05-30T10:28:06.155548Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18030,"took":"118.217743ms","hash":3497912114,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":30269440,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-30T10:28:06.15563Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3497912114,"revision":18030,"compact-revision":15462} {"level":"info","ts":"2026-05-30T10:31:10.828871Z","caller":"traceutil/trace.go:171","msg":"trace[2142168720] transaction","detail":"{read_only:false; response_revision:22227; number_of_response:1; }","duration":"132.253644ms","start":"2026-05-30T10:31:10.696594Z","end":"2026-05-30T10:31:10.828847Z","steps":["trace[2142168720] 'process raft request' (duration: 132.08819ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:33:06.038897Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20304} {"level":"info","ts":"2026-05-30T10:33:06.151269Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20304,"took":"106.334844ms","hash":522250086,"current-db-size-bytes":59117568,"current-db-size":"59 MB","current-db-size-in-use-bytes":42082304,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-30T10:33:06.151349Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":522250086,"revision":20304,"compact-revision":18030} {"level":"warn","ts":"2026-05-30T10:34:40.294204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.559318ms","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-30T10:34:40.294273Z","caller":"traceutil/trace.go:171","msg":"trace[703385031] 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:24325; }","duration":"225.682621ms","start":"2026-05-30T10:34:40.068578Z","end":"2026-05-30T10:34:40.29426Z","steps":["trace[703385031] 'count revisions from in-memory index tree' (duration: 225.419504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:40.294288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.305022ms","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-30T10:34:40.294366Z","caller":"traceutil/trace.go:171","msg":"trace[1979731344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24325; }","duration":"200.429995ms","start":"2026-05-30T10:34:40.093918Z","end":"2026-05-30T10:34:40.294348Z","steps":["trace[1979731344] 'range keys from in-memory index tree' (duration: 200.112129ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:34:40.607823Z","caller":"traceutil/trace.go:171","msg":"trace[2114062920] linearizableReadLoop","detail":"{readStateIndex:25123; appliedIndex:25122; }","duration":"117.970466ms","start":"2026-05-30T10:34:40.489831Z","end":"2026-05-30T10:34:40.607801Z","steps":["trace[2114062920] 'read index received' (duration: 117.810153ms)","trace[2114062920] 'applied index is now lower than readState.Index' (duration: 159.513µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:34:40.60787Z","caller":"traceutil/trace.go:171","msg":"trace[572112570] transaction","detail":"{read_only:false; response_revision:24327; number_of_response:1; }","duration":"125.652562ms","start":"2026-05-30T10:34:40.482199Z","end":"2026-05-30T10:34:40.607851Z","steps":["trace[572112570] 'process raft request' (duration: 125.460308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:40.608038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.93141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-30T10:34:40.608075Z","caller":"traceutil/trace.go:171","msg":"trace[684635117] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24327; }","duration":"114.999342ms","start":"2026-05-30T10:34:40.493066Z","end":"2026-05-30T10:34:40.608065Z","steps":["trace[684635117] 'agreement among raft nodes before linearized reading' (duration: 114.865189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:40.608084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.229271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-30T10:34:40.608156Z","caller":"traceutil/trace.go:171","msg":"trace[67031039] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24327; }","duration":"118.347555ms","start":"2026-05-30T10:34:40.489794Z","end":"2026-05-30T10:34:40.608141Z","steps":["trace[67031039] 'agreement among raft nodes before linearized reading' (duration: 118.132951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:40.984525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.32903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-05-30T10:34:40.984627Z","caller":"traceutil/trace.go:171","msg":"trace[527804815] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24329; }","duration":"135.462173ms","start":"2026-05-30T10:34:40.84914Z","end":"2026-05-30T10:34:40.984602Z","steps":["trace[527804815] 'range keys from in-memory index tree' (duration: 135.152017ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:34:40.997537Z","caller":"traceutil/trace.go:171","msg":"trace[1643521948] transaction","detail":"{read_only:false; response_revision:24330; number_of_response:1; }","duration":"107.558502ms","start":"2026-05-30T10:34:40.889955Z","end":"2026-05-30T10:34:40.997513Z","steps":["trace[1643521948] 'process raft request' (duration: 107.429869ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:34:41.994087Z","caller":"traceutil/trace.go:171","msg":"trace[557735571] transaction","detail":"{read_only:false; response_revision:24332; number_of_response:1; }","duration":"296.18229ms","start":"2026-05-30T10:34:41.697881Z","end":"2026-05-30T10:34:41.994063Z","steps":["trace[557735571] 'process raft request' (duration: 296.032427ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:34:42.282782Z","caller":"traceutil/trace.go:171","msg":"trace[1433045580] linearizableReadLoop","detail":"{readStateIndex:25131; appliedIndex:25127; }","duration":"382.558065ms","start":"2026-05-30T10:34:41.900207Z","end":"2026-05-30T10:34:42.282765Z","steps":["trace[1433045580] 'read index received' (duration: 93.826844ms)","trace[1433045580] 'applied index is now lower than readState.Index' (duration: 288.730561ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:34:42.282951Z","caller":"traceutil/trace.go:171","msg":"trace[217521407] transaction","detail":"{read_only:false; response_revision:24333; number_of_response:1; }","duration":"460.882016ms","start":"2026-05-30T10:34:41.822061Z","end":"2026-05-30T10:34:42.282943Z","steps":["trace[217521407] 'process raft request' (duration: 452.111646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:42.283019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:41.822043Z","time spent":"460.925897ms","remote":"127.0.0.1:48486","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-30T10:34:42.28313Z","caller":"traceutil/trace.go:171","msg":"trace[1704996679] transaction","detail":"{read_only:false; response_revision:24334; number_of_response:1; }","duration":"430.078111ms","start":"2026-05-30T10:34:41.853046Z","end":"2026-05-30T10:34:42.283124Z","steps":["trace[1704996679] 'process raft request' (duration: 429.61374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:42.283158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:41.853024Z","time spent":"430.118112ms","remote":"127.0.0.1:48486","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-30T10:34:42.28323Z","caller":"traceutil/trace.go:171","msg":"trace[345243815] transaction","detail":"{read_only:false; response_revision:24335; number_of_response:1; }","duration":"430.004439ms","start":"2026-05-30T10:34:41.853221Z","end":"2026-05-30T10:34:42.283226Z","steps":["trace[345243815] 'process raft request' (duration: 429.475248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:42.283259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:41.853215Z","time spent":"430.02693ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:34:42.28333Z","caller":"traceutil/trace.go:171","msg":"trace[1553894324] transaction","detail":"{read_only:false; response_revision:24336; number_of_response:1; }","duration":"337.436232ms","start":"2026-05-30T10:34:41.945889Z","end":"2026-05-30T10:34:42.283325Z","steps":["trace[1553894324] 'process raft request' (duration: 336.833879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:42.283359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:41.945867Z","time spent":"337.477353ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:34:42.283424Z","caller":"traceutil/trace.go:171","msg":"trace[1103900216] transaction","detail":"{read_only:false; response_revision:24337; number_of_response:1; }","duration":"336.573532ms","start":"2026-05-30T10:34:41.946846Z","end":"2026-05-30T10:34:42.283419Z","steps":["trace[1103900216] 'process raft request' (duration: 335.896508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:42.28345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:41.946831Z","time spent":"336.604014ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T10:34:42.283592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.386854ms","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-30T10:34:42.283611Z","caller":"traceutil/trace.go:171","msg":"trace[1187153811] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24337; }","duration":"383.440194ms","start":"2026-05-30T10:34:41.900165Z","end":"2026-05-30T10:34:42.283605Z","steps":["trace[1187153811] 'agreement among raft nodes before linearized reading' (duration: 383.344262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:42.283626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:41.900147Z","time spent":"383.475186ms","remote":"127.0.0.1:48486","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":"warn","ts":"2026-05-30T10:34:42.283734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.713055ms","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-30T10:34:42.283748Z","caller":"traceutil/trace.go:171","msg":"trace[129530394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24337; }","duration":"367.760246ms","start":"2026-05-30T10:34:41.915984Z","end":"2026-05-30T10:34:42.283744Z","steps":["trace[129530394] 'agreement among raft nodes before linearized reading' (duration: 367.715385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:42.283763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:41.915966Z","time spent":"367.794096ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T10:34:42.284061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.219589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-698b7bcb5b-6z2rb\" ","response":"range_response_count:1 size:8652"} {"level":"info","ts":"2026-05-30T10:34:42.284077Z","caller":"traceutil/trace.go:171","msg":"trace[822499413] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-698b7bcb5b-6z2rb; range_end:; response_count:1; response_revision:24337; }","duration":"167.26938ms","start":"2026-05-30T10:34:42.116803Z","end":"2026-05-30T10:34:42.284072Z","steps":["trace[822499413] 'agreement among raft nodes before linearized reading' (duration: 167.216569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:42.587479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.176085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-30T10:34:42.587637Z","caller":"traceutil/trace.go:171","msg":"trace[1361437940] transaction","detail":"{read_only:false; response_revision:24338; number_of_response:1; }","duration":"294.995806ms","start":"2026-05-30T10:34:42.292617Z","end":"2026-05-30T10:34:42.587613Z","steps":["trace[1361437940] 'process raft request' (duration: 135.589966ms)","trace[1361437940] 'compare' (duration: 158.595573ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:34:42.589313Z","caller":"traceutil/trace.go:171","msg":"trace[1456397644] linearizableReadLoop","detail":"{readStateIndex:25136; appliedIndex:25133; }","duration":"258.757743ms","start":"2026-05-30T10:34:42.330532Z","end":"2026-05-30T10:34:42.58929Z","steps":["trace[1456397644] 'read index received' (duration: 97.689157ms)","trace[1456397644] 'applied index is now lower than readState.Index' (duration: 161.067776ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:34:42.589686Z","caller":"traceutil/trace.go:171","msg":"trace[129388467] transaction","detail":"{read_only:false; response_revision:24339; number_of_response:1; }","duration":"295.799904ms","start":"2026-05-30T10:34:42.293872Z","end":"2026-05-30T10:34:42.589672Z","steps":["trace[129388467] 'process raft request' (duration: 295.228221ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:34:42.59006Z","caller":"traceutil/trace.go:171","msg":"trace[873427592] transaction","detail":"{read_only:false; response_revision:24340; number_of_response:1; }","duration":"286.288628ms","start":"2026-05-30T10:34:42.303734Z","end":"2026-05-30T10:34:42.590022Z","steps":["trace[873427592] 'process raft request' (duration: 285.495361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:42.590167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.97096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-05-30T10:34:42.590172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.617752ms","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-30T10:34:42.590222Z","caller":"traceutil/trace.go:171","msg":"trace[1626747460] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24340; }","duration":"121.078423ms","start":"2026-05-30T10:34:42.46913Z","end":"2026-05-30T10:34:42.590208Z","steps":["trace[1626747460] 'agreement among raft nodes before linearized reading' (duration: 120.917599ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:34:42.590241Z","caller":"traceutil/trace.go:171","msg":"trace[1940021289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24340; }","duration":"259.730684ms","start":"2026-05-30T10:34:42.330489Z","end":"2026-05-30T10:34:42.59022Z","steps":["trace[1940021289] 'agreement among raft nodes before linearized reading' (duration: 259.52699ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:34:42.934904Z","caller":"traceutil/trace.go:171","msg":"trace[2057982713] linearizableReadLoop","detail":"{readStateIndex:25142; appliedIndex:25137; }","duration":"324.451262ms","start":"2026-05-30T10:34:42.610428Z","end":"2026-05-30T10:34:42.934879Z","steps":["trace[2057982713] 'read index received' (duration: 324.114745ms)","trace[2057982713] 'applied index is now lower than readState.Index' (duration: 335.747µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:34:42.935132Z","caller":"traceutil/trace.go:171","msg":"trace[125756343] transaction","detail":"{read_only:false; response_revision:24342; number_of_response:1; }","duration":"338.269609ms","start":"2026-05-30T10:34:42.596854Z","end":"2026-05-30T10:34:42.935123Z","steps":["trace[125756343] 'process raft request' (duration: 337.754468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:42.935215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:42.596846Z","time spent":"338.30772ms","remote":"127.0.0.1:48500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1168,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:34:42.935418Z","caller":"traceutil/trace.go:171","msg":"trace[216081151] transaction","detail":"{read_only:false; response_revision:24343; number_of_response:1; }","duration":"338.042665ms","start":"2026-05-30T10:34:42.597367Z","end":"2026-05-30T10:34:42.93541Z","steps":["trace[216081151] 'process raft request' (duration: 337.353809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:42.935462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:42.597361Z","time spent":"338.080366ms","remote":"127.0.0.1:48564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T10:34:42.935637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.209539ms","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-30T10:34:42.935667Z","caller":"traceutil/trace.go:171","msg":"trace[1657774576] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24346; }","duration":"325.26455ms","start":"2026-05-30T10:34:42.610394Z","end":"2026-05-30T10:34:42.935658Z","steps":["trace[1657774576] 'agreement among raft nodes before linearized reading' (duration: 325.171078ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:34:42.935627Z","caller":"traceutil/trace.go:171","msg":"trace[866563093] transaction","detail":"{read_only:false; response_revision:24346; number_of_response:1; }","duration":"336.727268ms","start":"2026-05-30T10:34:42.598844Z","end":"2026-05-30T10:34:42.935571Z","steps":["trace[866563093] 'process raft request' (duration: 335.997311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:42.935691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:42.61038Z","time spent":"325.306551ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-30T10:34:42.935846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:42.598825Z","time spent":"336.88126ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:34:42.936037Z","caller":"traceutil/trace.go:171","msg":"trace[284864995] transaction","detail":"{read_only:false; response_revision:24345; number_of_response:1; }","duration":"337.508344ms","start":"2026-05-30T10:34:42.598507Z","end":"2026-05-30T10:34:42.936016Z","steps":["trace[284864995] 'process raft request' (duration: 336.295267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:42.936129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.069118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-30T10:34:42.936129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:42.598484Z","time spent":"337.601746ms","remote":"127.0.0.1:48736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7536,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:34:42.93616Z","caller":"traceutil/trace.go:171","msg":"trace[2117399942] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24346; }","duration":"251.12682ms","start":"2026-05-30T10:34:42.685021Z","end":"2026-05-30T10:34:42.936148Z","steps":["trace[2117399942] 'agreement among raft nodes before linearized reading' (duration: 251.073719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:42.936253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.283783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-30T10:34:42.936271Z","caller":"traceutil/trace.go:171","msg":"trace[1842409044] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24346; }","duration":"251.330624ms","start":"2026-05-30T10:34:42.684934Z","end":"2026-05-30T10:34:42.936264Z","steps":["trace[1842409044] 'agreement among raft nodes before linearized reading' (duration: 251.280923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:42.936058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.046986ms","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-30T10:34:42.936328Z","caller":"traceutil/trace.go:171","msg":"trace[113220284] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24346; }","duration":"256.343122ms","start":"2026-05-30T10:34:42.679971Z","end":"2026-05-30T10:34:42.936314Z","steps":["trace[113220284] 'agreement among raft nodes before linearized reading' (duration: 256.057746ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:34:42.936395Z","caller":"traceutil/trace.go:171","msg":"trace[859230893] transaction","detail":"{read_only:false; response_revision:24344; number_of_response:1; }","duration":"338.871593ms","start":"2026-05-30T10:34:42.597516Z","end":"2026-05-30T10:34:42.936387Z","steps":["trace[859230893] 'process raft request' (duration: 337.247887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:42.93644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:42.59751Z","time spent":"338.904313ms","remote":"127.0.0.1:48396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:34:43.297532Z","caller":"traceutil/trace.go:171","msg":"trace[688011839] transaction","detail":"{read_only:false; response_revision:24348; number_of_response:1; }","duration":"353.191061ms","start":"2026-05-30T10:34:42.944314Z","end":"2026-05-30T10:34:43.297505Z","steps":["trace[688011839] 'process raft request' (duration: 314.870264ms)","trace[688011839] 'compare' (duration: 37.376246ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:34:43.297673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:42.944294Z","time spent":"353.310633ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:34:43.29782Z","caller":"traceutil/trace.go:171","msg":"trace[292529785] transaction","detail":"{read_only:false; response_revision:24349; number_of_response:1; }","duration":"353.238452ms","start":"2026-05-30T10:34:42.944572Z","end":"2026-05-30T10:34:43.297811Z","steps":["trace[292529785] 'process raft request' (duration: 352.456635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:43.297866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:42.944548Z","time spent":"353.294893ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:34:43.298058Z","caller":"traceutil/trace.go:171","msg":"trace[2031429949] transaction","detail":"{read_only:false; response_revision:24351; number_of_response:1; }","duration":"296.891157ms","start":"2026-05-30T10:34:43.001146Z","end":"2026-05-30T10:34:43.298038Z","steps":["trace[2031429949] 'process raft request' (duration: 296.027208ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:34:43.298118Z","caller":"traceutil/trace.go:171","msg":"trace[804308451] transaction","detail":"{read_only:false; response_revision:24350; number_of_response:1; }","duration":"348.446779ms","start":"2026-05-30T10:34:42.949601Z","end":"2026-05-30T10:34:43.298048Z","steps":["trace[804308451] 'process raft request' (duration: 347.513089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:43.298247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.079228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-05-30T10:34:43.298282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:42.949589Z","time spent":"348.610562ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7836,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:34:43.298133Z","caller":"traceutil/trace.go:171","msg":"trace[1602934301] linearizableReadLoop","detail":"{readStateIndex:25145; appliedIndex:25142; }","duration":"351.850023ms","start":"2026-05-30T10:34:42.946166Z","end":"2026-05-30T10:34:43.298016Z","steps":["trace[1602934301] 'read index received' (duration: 51.693886ms)","trace[1602934301] 'applied index is now lower than readState.Index' (duration: 300.152307ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:34:43.298296Z","caller":"traceutil/trace.go:171","msg":"trace[297931414] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24352; }","duration":"352.16015ms","start":"2026-05-30T10:34:42.946124Z","end":"2026-05-30T10:34:43.298284Z","steps":["trace[297931414] 'agreement among raft nodes before linearized reading' (duration: 352.013987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:43.298418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:42.946108Z","time spent":"352.284652ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-30T10:34:43.298476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.602385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-30T10:34:43.298504Z","caller":"traceutil/trace.go:171","msg":"trace[2136949459] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:24352; }","duration":"217.651176ms","start":"2026-05-30T10:34:43.080845Z","end":"2026-05-30T10:34:43.298496Z","steps":["trace[2136949459] 'agreement among raft nodes before linearized reading' (duration: 217.573315ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:34:43.634167Z","caller":"traceutil/trace.go:171","msg":"trace[984706930] linearizableReadLoop","detail":"{readStateIndex:25149; appliedIndex:25148; }","duration":"107.149602ms","start":"2026-05-30T10:34:43.526994Z","end":"2026-05-30T10:34:43.634144Z","steps":["trace[984706930] 'read index received' (duration: 106.892106ms)","trace[984706930] 'applied index is now lower than readState.Index' (duration: 256.646µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:34:43.634228Z","caller":"traceutil/trace.go:171","msg":"trace[663701608] transaction","detail":"{read_only:false; response_revision:24353; number_of_response:1; }","duration":"127.155994ms","start":"2026-05-30T10:34:43.507035Z","end":"2026-05-30T10:34:43.634191Z","steps":["trace[663701608] 'process raft request' (duration: 126.936679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:43.634299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.290045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-30T10:34:43.634332Z","caller":"traceutil/trace.go:171","msg":"trace[821749614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24353; }","duration":"107.371557ms","start":"2026-05-30T10:34:43.52695Z","end":"2026-05-30T10:34:43.634322Z","steps":["trace[821749614] 'agreement among raft nodes before linearized reading' (duration: 107.292065ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:34:43.762392Z","caller":"traceutil/trace.go:171","msg":"trace[1466629974] transaction","detail":"{read_only:false; response_revision:24354; number_of_response:1; }","duration":"120.983731ms","start":"2026-05-30T10:34:43.641371Z","end":"2026-05-30T10:34:43.762354Z","steps":["trace[1466629974] 'process raft request' (duration: 119.58363ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:34:54.602143Z","caller":"traceutil/trace.go:171","msg":"trace[1330621051] transaction","detail":"{read_only:false; response_revision:24428; number_of_response:1; }","duration":"261.196246ms","start":"2026-05-30T10:34:54.340924Z","end":"2026-05-30T10:34:54.602121Z","steps":["trace[1330621051] 'process raft request' (duration: 260.978062ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:34:54.602159Z","caller":"traceutil/trace.go:171","msg":"trace[1154536988] linearizableReadLoop","detail":"{readStateIndex:25228; appliedIndex:25224; }","duration":"203.601154ms","start":"2026-05-30T10:34:54.398538Z","end":"2026-05-30T10:34:54.60214Z","steps":["trace[1154536988] 'read index received' (duration: 3.409754ms)","trace[1154536988] 'applied index is now lower than readState.Index' (duration: 200.19055ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:34:54.602243Z","caller":"traceutil/trace.go:171","msg":"trace[1278105328] transaction","detail":"{read_only:false; response_revision:24429; number_of_response:1; }","duration":"260.526162ms","start":"2026-05-30T10:34:54.341687Z","end":"2026-05-30T10:34:54.602213Z","steps":["trace[1278105328] 'process raft request' (duration: 260.361719ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:34:54.602276Z","caller":"traceutil/trace.go:171","msg":"trace[137617607] transaction","detail":"{read_only:false; response_revision:24430; number_of_response:1; }","duration":"259.583271ms","start":"2026-05-30T10:34:54.342663Z","end":"2026-05-30T10:34:54.602246Z","steps":["trace[137617607] 'process raft request' (duration: 259.430778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:54.602355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.803749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-30T10:34:54.602404Z","caller":"traceutil/trace.go:171","msg":"trace[1851016413] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:24430; }","duration":"203.86878ms","start":"2026-05-30T10:34:54.398522Z","end":"2026-05-30T10:34:54.602391Z","steps":["trace[1851016413] 'agreement among raft nodes before linearized reading' (duration: 203.709877ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:34:58.962878Z","caller":"traceutil/trace.go:171","msg":"trace[925849151] transaction","detail":"{read_only:false; response_revision:24522; number_of_response:1; }","duration":"277.195472ms","start":"2026-05-30T10:34:58.68566Z","end":"2026-05-30T10:34:58.962855Z","steps":["trace[925849151] 'process raft request' (duration: 277.07221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:59.309621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.971689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-30T10:34:59.31009Z","caller":"traceutil/trace.go:171","msg":"trace[1045758618] linearizableReadLoop","detail":"{readStateIndex:25323; appliedIndex:25320; }","duration":"543.970358ms","start":"2026-05-30T10:34:58.766096Z","end":"2026-05-30T10:34:59.310066Z","steps":["trace[1045758618] 'read index received' (duration: 196.696025ms)","trace[1045758618] 'applied index is now lower than readState.Index' (duration: 347.272943ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:34:59.310239Z","caller":"traceutil/trace.go:171","msg":"trace[795059861] transaction","detail":"{read_only:false; response_revision:24523; number_of_response:1; }","duration":"624.143969ms","start":"2026-05-30T10:34:58.686077Z","end":"2026-05-30T10:34:59.310221Z","steps":["trace[795059861] 'process raft request' (duration: 313.474165ms)","trace[795059861] 'compare' (duration: 309.780974ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:34:59.31033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:58.686059Z","time spent":"624.22303ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T10:34:59.310627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.800554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-30T10:34:59.310605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.462789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-05-30T10:34:59.310781Z","caller":"traceutil/trace.go:171","msg":"trace[277019263] transaction","detail":"{read_only:false; response_revision:24524; number_of_response:1; }","duration":"624.617988ms","start":"2026-05-30T10:34:58.686144Z","end":"2026-05-30T10:34:59.310762Z","steps":["trace[277019263] 'process raft request' (duration: 623.597427ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:34:59.310812Z","caller":"traceutil/trace.go:171","msg":"trace[479512583] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24524; }","duration":"544.723114ms","start":"2026-05-30T10:34:58.766065Z","end":"2026-05-30T10:34:59.310788Z","steps":["trace[479512583] 'agreement among raft nodes before linearized reading' (duration: 544.327535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:59.310855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.682513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-05-30T10:34:59.310885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:58.68614Z","time spent":"624.69065ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T10:34:59.310873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:58.766051Z","time spent":"544.805236ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":585,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-05-30T10:34:59.310657Z","caller":"traceutil/trace.go:171","msg":"trace[2136464887] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24524; }","duration":"245.903637ms","start":"2026-05-30T10:34:59.064746Z","end":"2026-05-30T10:34:59.31065Z","steps":["trace[2136464887] 'agreement among raft nodes before linearized reading' (duration: 245.808425ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:34:59.310913Z","caller":"traceutil/trace.go:171","msg":"trace[19916564] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:24524; }","duration":"151.781975ms","start":"2026-05-30T10:34:59.159119Z","end":"2026-05-30T10:34:59.310901Z","steps":["trace[19916564] 'agreement among raft nodes before linearized reading' (duration: 151.619682ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:34:59.636889Z","caller":"traceutil/trace.go:171","msg":"trace[1981206492] transaction","detail":"{read_only:false; response_revision:24525; number_of_response:1; }","duration":"321.496957ms","start":"2026-05-30T10:34:59.31535Z","end":"2026-05-30T10:34:59.636847Z","steps":["trace[1981206492] 'process raft request' (duration: 321.307603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:59.637022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:59.315329Z","time spent":"321.62289ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:34:59.63714Z","caller":"traceutil/trace.go:171","msg":"trace[1274468630] transaction","detail":"{read_only:false; response_revision:24526; number_of_response:1; }","duration":"321.321264ms","start":"2026-05-30T10:34:59.315797Z","end":"2026-05-30T10:34:59.637118Z","steps":["trace[1274468630] 'process raft request' (duration: 321.015927ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:34:59.637065Z","caller":"traceutil/trace.go:171","msg":"trace[1727819499] linearizableReadLoop","detail":"{readStateIndex:25326; appliedIndex:25324; }","duration":"313.631179ms","start":"2026-05-30T10:34:59.323402Z","end":"2026-05-30T10:34:59.637034Z","steps":["trace[1727819499] 'read index received' (duration: 13.4018ms)","trace[1727819499] 'applied index is now lower than readState.Index' (duration: 300.227269ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:34:59.637232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:59.315785Z","time spent":"321.398736ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T10:34:59.637437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.008428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e5e59cd9-e201-44cd-bf0f-48f2f87b1567\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-30T10:34:59.637508Z","caller":"traceutil/trace.go:171","msg":"trace[1876603368] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e5e59cd9-e201-44cd-bf0f-48f2f87b1567; range_end:; response_count:1; response_revision:24526; }","duration":"314.114019ms","start":"2026-05-30T10:34:59.323375Z","end":"2026-05-30T10:34:59.637489Z","steps":["trace[1876603368] 'agreement among raft nodes before linearized reading' (duration: 313.866744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:59.637557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:59.32336Z","time spent":"314.184661ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e5e59cd9-e201-44cd-bf0f-48f2f87b1567\" "} {"level":"info","ts":"2026-05-30T10:34:59.696332Z","caller":"traceutil/trace.go:171","msg":"trace[1750214737] transaction","detail":"{read_only:false; response_revision:24527; number_of_response:1; }","duration":"358.582369ms","start":"2026-05-30T10:34:59.337727Z","end":"2026-05-30T10:34:59.696309Z","steps":["trace[1750214737] 'process raft request' (duration: 358.430225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:59.696433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:59.337707Z","time spent":"358.67287ms","remote":"127.0.0.1:48256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T10:34:59.696431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.570284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-30T10:34:59.696527Z","caller":"traceutil/trace.go:171","msg":"trace[248307393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24528; }","duration":"172.706427ms","start":"2026-05-30T10:34:59.523811Z","end":"2026-05-30T10:34:59.696517Z","steps":["trace[248307393] 'agreement among raft nodes before linearized reading' (duration: 172.540273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:59.696618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.749351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-30T10:34:59.696637Z","caller":"traceutil/trace.go:171","msg":"trace[645908020] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24528; }","duration":"326.795282ms","start":"2026-05-30T10:34:59.369835Z","end":"2026-05-30T10:34:59.696631Z","steps":["trace[645908020] 'agreement among raft nodes before linearized reading' (duration: 326.726751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:59.696652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:59.369799Z","time spent":"326.849554ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-30T10:34:59.696678Z","caller":"traceutil/trace.go:171","msg":"trace[320953302] transaction","detail":"{read_only:false; response_revision:24528; number_of_response:1; }","duration":"343.641806ms","start":"2026-05-30T10:34:59.353011Z","end":"2026-05-30T10:34:59.696652Z","steps":["trace[320953302] 'process raft request' (duration: 343.245877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:59.696708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.645749ms","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-30T10:34:59.69679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:59.35299Z","time spent":"343.740648ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:34:59.696799Z","caller":"traceutil/trace.go:171","msg":"trace[2040142941] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24528; }","duration":"326.800803ms","start":"2026-05-30T10:34:59.369979Z","end":"2026-05-30T10:34:59.69678Z","steps":["trace[2040142941] 'agreement among raft nodes before linearized reading' (duration: 326.394264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:34:59.696876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:59.369967Z","time spent":"326.867754ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-30T10:35:00.089288Z","caller":"traceutil/trace.go:171","msg":"trace[532035808] transaction","detail":"{read_only:false; response_revision:24529; number_of_response:1; }","duration":"443.700665ms","start":"2026-05-30T10:34:59.645564Z","end":"2026-05-30T10:35:00.089264Z","steps":["trace[532035808] 'process raft request' (duration: 443.414549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:00.089459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:59.645545Z","time spent":"443.821108ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3746,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:35:00.08947Z","caller":"traceutil/trace.go:171","msg":"trace[1576919723] linearizableReadLoop","detail":"{readStateIndex:25330; appliedIndex:25328; }","duration":"393.149574ms","start":"2026-05-30T10:34:59.696292Z","end":"2026-05-30T10:35:00.089442Z","steps":["trace[1576919723] 'read index received' (duration: 392.699494ms)","trace[1576919723] 'applied index is now lower than readState.Index' (duration: 448.31µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:35:00.08957Z","caller":"traceutil/trace.go:171","msg":"trace[307285351] transaction","detail":"{read_only:false; response_revision:24530; number_of_response:1; }","duration":"395.507265ms","start":"2026-05-30T10:34:59.69402Z","end":"2026-05-30T10:35:00.089527Z","steps":["trace[307285351] 'process raft request' (duration: 395.165557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:00.089689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.852825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e5e59cd9-e201-44cd-bf0f-48f2f87b1567\" ","response":"range_response_count:1 size:3764"} {"level":"warn","ts":"2026-05-30T10:35:00.089713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:59.693992Z","time spent":"395.648798ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:35:00.089759Z","caller":"traceutil/trace.go:171","msg":"trace[1923098115] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e5e59cd9-e201-44cd-bf0f-48f2f87b1567; range_end:; response_count:1; response_revision:24530; }","duration":"403.977677ms","start":"2026-05-30T10:34:59.685761Z","end":"2026-05-30T10:35:00.089739Z","steps":["trace[1923098115] 'agreement among raft nodes before linearized reading' (duration: 403.835744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:00.089831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:59.685745Z","time spent":"404.07162ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3787,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e5e59cd9-e201-44cd-bf0f-48f2f87b1567\" "} {"level":"info","ts":"2026-05-30T10:35:00.116847Z","caller":"traceutil/trace.go:171","msg":"trace[1406339968] transaction","detail":"{read_only:false; response_revision:24531; number_of_response:1; }","duration":"416.420166ms","start":"2026-05-30T10:34:59.700408Z","end":"2026-05-30T10:35:00.116828Z","steps":["trace[1406339968] 'process raft request' (duration: 414.233668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:00.116964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:59.700392Z","time spent":"416.510808ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:35:00.117051Z","caller":"traceutil/trace.go:171","msg":"trace[925474643] transaction","detail":"{read_only:false; response_revision:24532; number_of_response:1; }","duration":"416.562449ms","start":"2026-05-30T10:34:59.700459Z","end":"2026-05-30T10:35:00.117022Z","steps":["trace[925474643] 'process raft request' (duration: 416.334754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:00.117117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.299248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-30T10:35:00.117153Z","caller":"traceutil/trace.go:171","msg":"trace[1575003322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24532; }","duration":"419.377351ms","start":"2026-05-30T10:34:59.697766Z","end":"2026-05-30T10:35:00.117144Z","steps":["trace[1575003322] 'agreement among raft nodes before linearized reading' (duration: 419.235968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:00.117182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:59.697737Z","time spent":"419.438281ms","remote":"127.0.0.1:48238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-30T10:35:00.117186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:59.700441Z","time spent":"416.675412ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T10:35:00.117313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.595823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-05-30T10:35:00.117427Z","caller":"traceutil/trace.go:171","msg":"trace[1985614635] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24532; }","duration":"418.719486ms","start":"2026-05-30T10:34:59.698664Z","end":"2026-05-30T10:35:00.117383Z","steps":["trace[1985614635] 'agreement among raft nodes before linearized reading' (duration: 418.43167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:00.117488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:34:59.698655Z","time spent":"418.814909ms","remote":"127.0.0.1:48396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-05-30T10:35:00.313815Z","caller":"traceutil/trace.go:171","msg":"trace[598546523] linearizableReadLoop","detail":"{readStateIndex:25333; appliedIndex:25332; }","duration":"194.811704ms","start":"2026-05-30T10:35:00.118983Z","end":"2026-05-30T10:35:00.313794Z","steps":["trace[598546523] 'read index received' (duration: 194.694382ms)","trace[598546523] 'applied index is now lower than readState.Index' (duration: 116.272µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:35:00.313925Z","caller":"traceutil/trace.go:171","msg":"trace[94180891] transaction","detail":"{read_only:false; response_revision:24533; number_of_response:1; }","duration":"207.867516ms","start":"2026-05-30T10:35:00.106044Z","end":"2026-05-30T10:35:00.313911Z","steps":["trace[94180891] 'process raft request' (duration: 207.61464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:00.314198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.192833ms","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-30T10:35:00.314274Z","caller":"traceutil/trace.go:171","msg":"trace[295986672] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:24533; }","duration":"195.308805ms","start":"2026-05-30T10:35:00.118947Z","end":"2026-05-30T10:35:00.314256Z","steps":["trace[295986672] 'agreement among raft nodes before linearized reading' (duration: 195.044129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:00.549097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.765659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-30T10:35:00.54922Z","caller":"traceutil/trace.go:171","msg":"trace[1335695370] linearizableReadLoop","detail":"{readStateIndex:25334; appliedIndex:25333; }","duration":"235.322868ms","start":"2026-05-30T10:35:00.313877Z","end":"2026-05-30T10:35:00.5492Z","steps":["trace[1335695370] 'read index received' (duration: 93.362714ms)","trace[1335695370] 'applied index is now lower than readState.Index' (duration: 141.958734ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:35:00.549264Z","caller":"traceutil/trace.go:171","msg":"trace[1996191926] transaction","detail":"{read_only:false; response_revision:24534; number_of_response:1; }","duration":"416.255622ms","start":"2026-05-30T10:35:00.132981Z","end":"2026-05-30T10:35:00.549237Z","steps":["trace[1996191926] 'process raft request' (duration: 274.246428ms)","trace[1996191926] 'compare' (duration: 141.581195ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:35:00.54938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:00.132958Z","time spent":"416.358145ms","remote":"127.0.0.1:48486","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-30T10:35:00.549428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.557158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-30T10:35:00.549541Z","caller":"traceutil/trace.go:171","msg":"trace[1146591127] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:24534; }","duration":"390.713531ms","start":"2026-05-30T10:35:00.158798Z","end":"2026-05-30T10:35:00.549512Z","steps":["trace[1146591127] 'agreement among raft nodes before linearized reading' (duration: 390.500527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:00.549609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:00.158779Z","time spent":"390.813983ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-05-30T10:35:00.581393Z","caller":"traceutil/trace.go:171","msg":"trace[55212303] transaction","detail":"{read_only:false; response_revision:24535; number_of_response:1; }","duration":"259.625942ms","start":"2026-05-30T10:35:00.321745Z","end":"2026-05-30T10:35:00.581371Z","steps":["trace[55212303] 'process raft request' (duration: 259.449098ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:00.581422Z","caller":"traceutil/trace.go:171","msg":"trace[43315967] transaction","detail":"{read_only:false; response_revision:24537; number_of_response:1; }","duration":"258.719953ms","start":"2026-05-30T10:35:00.322688Z","end":"2026-05-30T10:35:00.581408Z","steps":["trace[43315967] 'process raft request' (duration: 258.665102ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:00.581529Z","caller":"traceutil/trace.go:171","msg":"trace[188648980] transaction","detail":"{read_only:false; response_revision:24536; number_of_response:1; }","duration":"259.50264ms","start":"2026-05-30T10:35:00.322019Z","end":"2026-05-30T10:35:00.581522Z","steps":["trace[188648980] 'process raft request' (duration: 259.288955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:00.88141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.212149ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-30T10:35:00.881516Z","caller":"traceutil/trace.go:171","msg":"trace[796817451] linearizableReadLoop","detail":"{readStateIndex:25338; appliedIndex:25334; }","duration":"332.23933ms","start":"2026-05-30T10:35:00.54926Z","end":"2026-05-30T10:35:00.881499Z","steps":["trace[796817451] 'read index received' (duration: 31.916908ms)","trace[796817451] 'applied index is now lower than readState.Index' (duration: 300.321252ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:35:00.881607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.667351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-30T10:35:00.881647Z","caller":"traceutil/trace.go:171","msg":"trace[423323935] transaction","detail":"{read_only:false; response_revision:24538; number_of_response:1; }","duration":"462.517141ms","start":"2026-05-30T10:35:00.419114Z","end":"2026-05-30T10:35:00.881631Z","steps":["trace[423323935] 'process raft request' (duration: 276.005875ms)","trace[423323935] 'compare' (duration: 185.988785ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:35:00.88175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.752295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-30T10:35:00.881658Z","caller":"traceutil/trace.go:171","msg":"trace[349425107] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:24538; }","duration":"403.731042ms","start":"2026-05-30T10:35:00.477898Z","end":"2026-05-30T10:35:00.881629Z","steps":["trace[349425107] 'agreement among raft nodes before linearized reading' (duration: 403.671611ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:00.881786Z","caller":"traceutil/trace.go:171","msg":"trace[2017103760] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:24538; }","duration":"563.808226ms","start":"2026-05-30T10:35:00.317967Z","end":"2026-05-30T10:35:00.881775Z","steps":["trace[2017103760] 'agreement among raft nodes before linearized reading' (duration: 563.705464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:00.881793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:00.477881Z","time spent":"403.904706ms","remote":"127.0.0.1:33788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true "} {"level":"warn","ts":"2026-05-30T10:35:00.881796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.421783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-30T10:35:00.881584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.29089ms","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":"warn","ts":"2026-05-30T10:35:00.881819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:00.317945Z","time spent":"563.864738ms","remote":"127.0.0.1:48500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-05-30T10:35:00.881797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.903786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-30T10:35:00.881896Z","caller":"traceutil/trace.go:171","msg":"trace[1985277172] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:24538; }","duration":"492.052229ms","start":"2026-05-30T10:35:00.389818Z","end":"2026-05-30T10:35:00.88187Z","steps":["trace[1985277172] 'agreement among raft nodes before linearized reading' (duration: 491.859275ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:00.881889Z","caller":"traceutil/trace.go:171","msg":"trace[183595530] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:24538; }","duration":"357.596897ms","start":"2026-05-30T10:35:00.524239Z","end":"2026-05-30T10:35:00.881836Z","steps":["trace[183595530] 'agreement among raft nodes before linearized reading' (duration: 357.26282ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:00.881839Z","caller":"traceutil/trace.go:171","msg":"trace[1390017098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24538; }","duration":"357.473964ms","start":"2026-05-30T10:35:00.524355Z","end":"2026-05-30T10:35:00.881829Z","steps":["trace[1390017098] 'agreement among raft nodes before linearized reading' (duration: 357.410253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:00.881959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:00.389793Z","time spent":"492.150911ms","remote":"127.0.0.1:52842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":31,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-05-30T10:35:00.881997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:00.524223Z","time spent":"357.72539ms","remote":"127.0.0.1:52888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":37,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-05-30T10:35:00.881746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:00.419093Z","time spent":"462.592532ms","remote":"127.0.0.1:48486","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-30T10:35:00.882025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:00.524351Z","time spent":"357.631538ms","remote":"127.0.0.1:48230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-30T10:35:00.975856Z","caller":"traceutil/trace.go:171","msg":"trace[1891888184] transaction","detail":"{read_only:false; response_revision:24539; number_of_response:1; }","duration":"389.524355ms","start":"2026-05-30T10:35:00.586289Z","end":"2026-05-30T10:35:00.975813Z","steps":["trace[1891888184] 'process raft request' (duration: 389.320381ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:00.975902Z","caller":"traceutil/trace.go:171","msg":"trace[1817097436] transaction","detail":"{read_only:false; response_revision:24542; number_of_response:1; }","duration":"356.124145ms","start":"2026-05-30T10:35:00.619759Z","end":"2026-05-30T10:35:00.975883Z","steps":["trace[1817097436] 'process raft request' (duration: 356.067364ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:00.975887Z","caller":"traceutil/trace.go:171","msg":"trace[484697575] transaction","detail":"{read_only:false; response_revision:24540; number_of_response:1; }","duration":"389.378062ms","start":"2026-05-30T10:35:00.586476Z","end":"2026-05-30T10:35:00.975854Z","steps":["trace[484697575] 'process raft request' (duration: 389.248739ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:00.975956Z","caller":"traceutil/trace.go:171","msg":"trace[892355061] transaction","detail":"{read_only:false; response_revision:24541; number_of_response:1; }","duration":"386.898029ms","start":"2026-05-30T10:35:00.589029Z","end":"2026-05-30T10:35:00.975927Z","steps":["trace[892355061] 'process raft request' (duration: 386.758086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:00.976006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:00.586268Z","time spent":"389.665188ms","remote":"127.0.0.1:48308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":720,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T10:35:00.976019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:00.619737Z","time spent":"356.217806ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T10:35:00.976064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:00.586456Z","time spent":"389.515335ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5660,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T10:35:00.976087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:00.589014Z","time spent":"387.005891ms","remote":"127.0.0.1:48462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5599,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:35:00.976247Z","caller":"traceutil/trace.go:171","msg":"trace[1746959966] transaction","detail":"{read_only:false; response_revision:24544; number_of_response:1; }","duration":"351.045535ms","start":"2026-05-30T10:35:00.62519Z","end":"2026-05-30T10:35:00.976235Z","steps":["trace[1746959966] 'process raft request' (duration: 350.897831ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:00.976243Z","caller":"traceutil/trace.go:171","msg":"trace[2016114921] transaction","detail":"{read_only:false; response_revision:24543; number_of_response:1; }","duration":"356.402231ms","start":"2026-05-30T10:35:00.619805Z","end":"2026-05-30T10:35:00.976208Z","steps":["trace[2016114921] 'process raft request' (duration: 356.049393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:00.976303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:00.625173Z","time spent":"351.099017ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T10:35:00.976335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.464455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-30T10:35:00.976382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:00.619779Z","time spent":"356.527344ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:35:00.976411Z","caller":"traceutil/trace.go:171","msg":"trace[1686607651] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:24544; }","duration":"180.576328ms","start":"2026-05-30T10:35:00.795812Z","end":"2026-05-30T10:35:00.976389Z","steps":["trace[1686607651] 'agreement among raft nodes before linearized reading' (duration: 180.439275ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:01.29544Z","caller":"traceutil/trace.go:171","msg":"trace[1381064317] linearizableReadLoop","detail":"{readStateIndex:25346; appliedIndex:25344; }","duration":"297.833497ms","start":"2026-05-30T10:35:00.99759Z","end":"2026-05-30T10:35:01.295424Z","steps":["trace[1381064317] 'read index received' (duration: 219.689301ms)","trace[1381064317] 'applied index is now lower than readState.Index' (duration: 78.143476ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:35:01.295542Z","caller":"traceutil/trace.go:171","msg":"trace[1157705743] transaction","detail":"{read_only:false; response_revision:24546; number_of_response:1; }","duration":"314.918186ms","start":"2026-05-30T10:35:00.980577Z","end":"2026-05-30T10:35:01.295495Z","steps":["trace[1157705743] 'process raft request' (duration: 314.787833ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:01.295589Z","caller":"traceutil/trace.go:171","msg":"trace[155604914] transaction","detail":"{read_only:false; response_revision:24545; number_of_response:1; }","duration":"315.910707ms","start":"2026-05-30T10:35:00.979586Z","end":"2026-05-30T10:35:01.295496Z","steps":["trace[155604914] 'process raft request' (duration: 237.67746ms)","trace[155604914] 'compare' (duration: 77.931561ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:35:01.295645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.867327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e5e59cd9-e201-44cd-bf0f-48f2f87b1567\" ","response":"range_response_count:1 size:3764"} {"level":"warn","ts":"2026-05-30T10:35:01.295646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.048533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/heat-engine-cleaner-29668955-ltksv\" ","response":"range_response_count:1 size:5678"} {"level":"info","ts":"2026-05-30T10:35:01.295674Z","caller":"traceutil/trace.go:171","msg":"trace[453854763] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e5e59cd9-e201-44cd-bf0f-48f2f87b1567; range_end:; response_count:1; response_revision:24546; }","duration":"201.939479ms","start":"2026-05-30T10:35:01.093725Z","end":"2026-05-30T10:35:01.295665Z","steps":["trace[453854763] 'agreement among raft nodes before linearized reading' (duration: 201.833375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:01.295676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:00.98056Z","time spent":"315.043839ms","remote":"127.0.0.1:48486","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-30T10:35:01.295707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:00.979569Z","time spent":"316.074231ms","remote":"127.0.0.1:48308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T10:35:01.295733Z","caller":"traceutil/trace.go:171","msg":"trace[314865896] range","detail":"{range_begin:/registry/pods/openstack/heat-engine-cleaner-29668955-ltksv; range_end:; response_count:1; response_revision:24546; }","duration":"298.120224ms","start":"2026-05-30T10:35:00.997548Z","end":"2026-05-30T10:35:01.295668Z","steps":["trace[314865896] 'agreement among raft nodes before linearized reading' (duration: 297.99944ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:01.648556Z","caller":"traceutil/trace.go:171","msg":"trace[2015536849] linearizableReadLoop","detail":"{readStateIndex:25347; appliedIndex:25346; }","duration":"333.208719ms","start":"2026-05-30T10:35:01.315332Z","end":"2026-05-30T10:35:01.648541Z","steps":["trace[2015536849] 'read index received' (duration: 333.082477ms)","trace[2015536849] 'applied index is now lower than readState.Index' (duration: 125.472µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:35:01.648637Z","caller":"traceutil/trace.go:171","msg":"trace[1968411302] transaction","detail":"{read_only:false; response_revision:24547; number_of_response:1; }","duration":"344.007564ms","start":"2026-05-30T10:35:01.304597Z","end":"2026-05-30T10:35:01.648605Z","steps":["trace[1968411302] 'process raft request' (duration: 343.823909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:01.648734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.331873ms","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-30T10:35:01.648767Z","caller":"traceutil/trace.go:171","msg":"trace[610920419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24547; }","duration":"333.389974ms","start":"2026-05-30T10:35:01.315367Z","end":"2026-05-30T10:35:01.648757Z","steps":["trace[610920419] 'agreement among raft nodes before linearized reading' (duration: 333.282382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:01.648795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:01.315363Z","time spent":"333.423704ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T10:35:01.648812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:01.304572Z","time spent":"344.136696ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6970,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T10:35:01.648862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.142068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-30T10:35:01.648921Z","caller":"traceutil/trace.go:171","msg":"trace[775549351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24547; }","duration":"123.199539ms","start":"2026-05-30T10:35:01.525706Z","end":"2026-05-30T10:35:01.648905Z","steps":["trace[775549351] 'agreement among raft nodes before linearized reading' (duration: 123.085077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:01.648847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.480716ms","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-30T10:35:01.648955Z","caller":"traceutil/trace.go:171","msg":"trace[171983281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24547; }","duration":"333.65745ms","start":"2026-05-30T10:35:01.315291Z","end":"2026-05-30T10:35:01.648948Z","steps":["trace[171983281] 'agreement among raft nodes before linearized reading' (duration: 333.351163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:01.648987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:01.315273Z","time spent":"333.705961ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-30T10:35:02.00189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.635481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-30T10:35:02.002066Z","caller":"traceutil/trace.go:171","msg":"trace[1794389600] transaction","detail":"{read_only:false; response_revision:24550; number_of_response:1; }","duration":"348.721246ms","start":"2026-05-30T10:35:01.653331Z","end":"2026-05-30T10:35:02.002052Z","steps":["trace[1794389600] 'process raft request' (duration: 348.638184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:02.002111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:01.653307Z","time spent":"348.786138ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:35:02.002204Z","caller":"traceutil/trace.go:171","msg":"trace[1250780789] transaction","detail":"{read_only:false; response_revision:24549; number_of_response:1; }","duration":"348.987181ms","start":"2026-05-30T10:35:01.653188Z","end":"2026-05-30T10:35:02.002175Z","steps":["trace[1250780789] 'process raft request' (duration: 57.988212ms)","trace[1250780789] 'compare' (duration: 290.210632ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:35:02.002345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:01.653165Z","time spent":"349.109234ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:35:02.070183Z","caller":"traceutil/trace.go:171","msg":"trace[1126411274] transaction","detail":"{read_only:false; response_revision:24551; number_of_response:1; }","duration":"367.346037ms","start":"2026-05-30T10:35:01.70281Z","end":"2026-05-30T10:35:02.070156Z","steps":["trace[1126411274] 'process raft request' (duration: 367.172973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:02.070338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:01.70276Z","time spent":"367.49755ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:35:02.308701Z","caller":"traceutil/trace.go:171","msg":"trace[2143314639] linearizableReadLoop","detail":"{readStateIndex:25352; appliedIndex:25351; }","duration":"187.892264ms","start":"2026-05-30T10:35:02.120781Z","end":"2026-05-30T10:35:02.308673Z","steps":["trace[2143314639] 'read index received' (duration: 167.750959ms)","trace[2143314639] 'applied index is now lower than readState.Index' (duration: 20.140295ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:35:02.308785Z","caller":"traceutil/trace.go:171","msg":"trace[1274718702] transaction","detail":"{read_only:false; response_revision:24552; number_of_response:1; }","duration":"214.763543ms","start":"2026-05-30T10:35:02.093986Z","end":"2026-05-30T10:35:02.30875Z","steps":["trace[1274718702] 'process raft request' (duration: 194.525967ms)","trace[1274718702] 'compare' (duration: 19.93774ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:35:02.309064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.267003ms","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-30T10:35:02.30916Z","caller":"traceutil/trace.go:171","msg":"trace[244422876] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24552; }","duration":"188.402105ms","start":"2026-05-30T10:35:02.120735Z","end":"2026-05-30T10:35:02.309137Z","steps":["trace[244422876] 'agreement among raft nodes before linearized reading' (duration: 188.013006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:02.319739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.412471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-30T10:35:02.319805Z","caller":"traceutil/trace.go:171","msg":"trace[1148743981] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24553; }","duration":"198.497574ms","start":"2026-05-30T10:35:02.121291Z","end":"2026-05-30T10:35:02.319789Z","steps":["trace[1148743981] 'agreement among raft nodes before linearized reading' (duration: 198.32929ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:02.721281Z","caller":"traceutil/trace.go:171","msg":"trace[2074574501] transaction","detail":"{read_only:false; response_revision:24554; number_of_response:1; }","duration":"408.445423ms","start":"2026-05-30T10:35:02.312806Z","end":"2026-05-30T10:35:02.721251Z","steps":["trace[2074574501] 'process raft request' (duration: 335.96957ms)","trace[2074574501] 'compare' (duration: 71.89405ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:35:02.721469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:02.312786Z","time spent":"408.596396ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:35:02.721527Z","caller":"traceutil/trace.go:171","msg":"trace[496863305] transaction","detail":"{read_only:false; response_revision:24555; number_of_response:1; }","duration":"408.336032ms","start":"2026-05-30T10:35:02.313164Z","end":"2026-05-30T10:35:02.7215Z","steps":["trace[496863305] 'process raft request' (duration: 408.006734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:02.721659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:02.313157Z","time spent":"408.435014ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:35:02.781694Z","caller":"traceutil/trace.go:171","msg":"trace[618425328] transaction","detail":"{read_only:false; response_revision:24556; number_of_response:1; }","duration":"457.904881ms","start":"2026-05-30T10:35:02.323763Z","end":"2026-05-30T10:35:02.781668Z","steps":["trace[618425328] 'process raft request' (duration: 457.563244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:02.781842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:02.323746Z","time spent":"458.021233ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:35:02.78202Z","caller":"traceutil/trace.go:171","msg":"trace[1058594862] linearizableReadLoop","detail":"{readStateIndex:25357; appliedIndex:25353; }","duration":"290.9752ms","start":"2026-05-30T10:35:02.491016Z","end":"2026-05-30T10:35:02.781992Z","steps":["trace[1058594862] 'read index received' (duration: 157.778346ms)","trace[1058594862] 'applied index is now lower than readState.Index' (duration: 133.195634ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:35:02.782069Z","caller":"traceutil/trace.go:171","msg":"trace[2102506082] transaction","detail":"{read_only:false; response_revision:24557; number_of_response:1; }","duration":"441.764284ms","start":"2026-05-30T10:35:02.340265Z","end":"2026-05-30T10:35:02.782029Z","steps":["trace[2102506082] 'process raft request' (duration: 441.198952ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:02.78216Z","caller":"traceutil/trace.go:171","msg":"trace[863338083] transaction","detail":"{read_only:false; response_revision:24558; number_of_response:1; }","duration":"228.49462ms","start":"2026-05-30T10:35:02.553652Z","end":"2026-05-30T10:35:02.782147Z","steps":["trace[863338083] 'process raft request' (duration: 228.219094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:02.782203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:02.340231Z","time spent":"441.907827ms","remote":"127.0.0.1:42566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2878,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T10:35:02.782215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.261939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-30T10:35:02.782247Z","caller":"traceutil/trace.go:171","msg":"trace[1233253393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24558; }","duration":"256.328361ms","start":"2026-05-30T10:35:02.525909Z","end":"2026-05-30T10:35:02.782238Z","steps":["trace[1233253393] 'agreement among raft nodes before linearized reading' (duration: 256.27671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:02.782527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.506561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-05-30T10:35:02.782566Z","caller":"traceutil/trace.go:171","msg":"trace[962542416] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24558; }","duration":"291.570372ms","start":"2026-05-30T10:35:02.490984Z","end":"2026-05-30T10:35:02.782554Z","steps":["trace[962542416] 'agreement among raft nodes before linearized reading' (duration: 291.377468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:03.397209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.780361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-30T10:35:03.397303Z","caller":"traceutil/trace.go:171","msg":"trace[1777322962] transaction","detail":"{read_only:false; response_revision:24559; number_of_response:1; }","duration":"509.636498ms","start":"2026-05-30T10:35:02.887649Z","end":"2026-05-30T10:35:03.397286Z","steps":["trace[1777322962] 'process raft request' (duration: 290.709813ms)","trace[1777322962] 'compare' (duration: 218.108866ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:35:03.397362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:02.887624Z","time spent":"509.70822ms","remote":"127.0.0.1:48486","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-30T10:35:03.961252Z","caller":"traceutil/trace.go:171","msg":"trace[618336011] linearizableReadLoop","detail":"{readStateIndex:25362; appliedIndex:25358; }","duration":"964.275768ms","start":"2026-05-30T10:35:02.996959Z","end":"2026-05-30T10:35:03.961234Z","steps":["trace[618336011] 'read index received' (duration: 181.417165ms)","trace[618336011] 'applied index is now lower than readState.Index' (duration: 782.857753ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:35:03.961519Z","caller":"traceutil/trace.go:171","msg":"trace[949720597] transaction","detail":"{read_only:false; response_revision:24560; number_of_response:1; }","duration":"979.921276ms","start":"2026-05-30T10:35:02.981588Z","end":"2026-05-30T10:35:03.961509Z","steps":["trace[949720597] 'process raft request' (duration: 979.473756ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:03.961588Z","caller":"traceutil/trace.go:171","msg":"trace[428338184] transaction","detail":"{read_only:false; response_revision:24562; number_of_response:1; }","duration":"979.408874ms","start":"2026-05-30T10:35:02.982158Z","end":"2026-05-30T10:35:03.961567Z","steps":["trace[428338184] 'process raft request' (duration: 979.049246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:03.961687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:02.981567Z","time spent":"979.976757ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T10:35:03.96169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:02.982151Z","time spent":"979.484505ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:35:03.961693Z","caller":"traceutil/trace.go:171","msg":"trace[508375764] transaction","detail":"{read_only:false; response_revision:24561; number_of_response:1; }","duration":"979.797094ms","start":"2026-05-30T10:35:02.98186Z","end":"2026-05-30T10:35:03.961658Z","steps":["trace[508375764] 'process raft request' (duration: 979.319014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:03.961842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.878541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1987"} {"level":"warn","ts":"2026-05-30T10:35:03.961859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:02.98184Z","time spent":"979.928366ms","remote":"127.0.0.1:48486","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-30T10:35:03.961878Z","caller":"traceutil/trace.go:171","msg":"trace[850631998] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:24562; }","duration":"964.948732ms","start":"2026-05-30T10:35:02.996916Z","end":"2026-05-30T10:35:03.961864Z","steps":["trace[850631998] 'agreement among raft nodes before linearized reading' (duration: 964.876421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:03.961908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:02.996897Z","time spent":"965.004884ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2010,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-05-30T10:35:04.188435Z","caller":"traceutil/trace.go:171","msg":"trace[1613849843] transaction","detail":"{read_only:false; response_revision:24563; number_of_response:1; }","duration":"440.941204ms","start":"2026-05-30T10:35:03.747466Z","end":"2026-05-30T10:35:04.188408Z","steps":["trace[1613849843] 'process raft request' (duration: 415.683799ms)","trace[1613849843] 'compare' (duration: 25.161853ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:35:04.188474Z","caller":"traceutil/trace.go:171","msg":"trace[666743603] transaction","detail":"{read_only:false; response_revision:24564; number_of_response:1; }","duration":"113.56905ms","start":"2026-05-30T10:35:04.074889Z","end":"2026-05-30T10:35:04.188458Z","steps":["trace[666743603] 'process raft request' (duration: 113.523469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:04.188557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:03.747444Z","time spent":"441.061487ms","remote":"127.0.0.1:48308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":907,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T10:35:04.188632Z","caller":"traceutil/trace.go:171","msg":"trace[1989806660] linearizableReadLoop","detail":"{readStateIndex:25363; appliedIndex:25362; }","duration":"227.325966ms","start":"2026-05-30T10:35:03.9613Z","end":"2026-05-30T10:35:04.188626Z","steps":["trace[1989806660] 'read index received' (duration: 201.861807ms)","trace[1989806660] 'applied index is now lower than readState.Index' (duration: 25.463509ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:35:04.188817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.178283ms","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-30T10:35:04.188849Z","caller":"traceutil/trace.go:171","msg":"trace[1433037477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24564; }","duration":"506.257524ms","start":"2026-05-30T10:35:03.682587Z","end":"2026-05-30T10:35:04.188844Z","steps":["trace[1433037477] 'agreement among raft nodes before linearized reading' (duration: 506.164942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:04.188864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:03.682567Z","time spent":"506.293255ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T10:35:04.188985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.652895ms","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-30T10:35:04.189015Z","caller":"traceutil/trace.go:171","msg":"trace[60258845] range","detail":"{range_begin:/registry/ingress/openstack/container-infra; range_end:; response_count:1; response_revision:24564; }","duration":"223.706708ms","start":"2026-05-30T10:35:03.96529Z","end":"2026-05-30T10:35:04.188997Z","steps":["trace[60258845] 'agreement among raft nodes before linearized reading' (duration: 223.638056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:04.189128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.997978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"warn","ts":"2026-05-30T10:35:04.189125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044174882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-30T10:35:04.189147Z","caller":"traceutil/trace.go:171","msg":"trace[333110933] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:24564; }","duration":"164.06918ms","start":"2026-05-30T10:35:04.025073Z","end":"2026-05-30T10:35:04.189142Z","steps":["trace[333110933] 'agreement among raft nodes before linearized reading' (duration: 164.016939ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:04.189166Z","caller":"traceutil/trace.go:171","msg":"trace[44892274] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:24564; }","duration":"1.044468708s","start":"2026-05-30T10:35:03.144685Z","end":"2026-05-30T10:35:04.189154Z","steps":["trace[44892274] 'agreement among raft nodes before linearized reading' (duration: 1.044153981s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:04.1892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:03.144661Z","time spent":"1.04453002s","remote":"127.0.0.1:34098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-30T10:35:04.189239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.639509ms","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-30T10:35:04.189252Z","caller":"traceutil/trace.go:171","msg":"trace[1550094917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24564; }","duration":"181.67547ms","start":"2026-05-30T10:35:04.007573Z","end":"2026-05-30T10:35:04.189248Z","steps":["trace[1550094917] 'agreement among raft nodes before linearized reading' (duration: 181.635879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:04.189342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.172963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-30T10:35:04.189358Z","caller":"traceutil/trace.go:171","msg":"trace[569725965] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24564; }","duration":"183.224973ms","start":"2026-05-30T10:35:04.006129Z","end":"2026-05-30T10:35:04.189354Z","steps":["trace[569725965] 'agreement among raft nodes before linearized reading' (duration: 183.186622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:04.189437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.070975ms","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-30T10:35:04.189452Z","caller":"traceutil/trace.go:171","msg":"trace[276349029] range","detail":"{range_begin:/registry/ingress/openstack/container-infra-registry; range_end:; response_count:1; response_revision:24564; }","duration":"224.100586ms","start":"2026-05-30T10:35:03.965347Z","end":"2026-05-30T10:35:04.189448Z","steps":["trace[276349029] 'agreement among raft nodes before linearized reading' (duration: 224.060405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:04.189454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.333645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-30T10:35:04.18949Z","caller":"traceutil/trace.go:171","msg":"trace[120331850] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24564; }","duration":"537.403167ms","start":"2026-05-30T10:35:03.652077Z","end":"2026-05-30T10:35:04.18948Z","steps":["trace[120331850] 'agreement among raft nodes before linearized reading' (duration: 537.270583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:04.18952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:03.652058Z","time spent":"537.456608ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-30T10:35:04.189674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.400778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-30T10:35:04.18971Z","caller":"traceutil/trace.go:171","msg":"trace[2147274592] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:24564; }","duration":"664.46423ms","start":"2026-05-30T10:35:03.525235Z","end":"2026-05-30T10:35:04.189699Z","steps":["trace[2147274592] 'agreement among raft nodes before linearized reading' (duration: 664.414869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:04.189742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:03.525219Z","time spent":"664.51552ms","remote":"127.0.0.1:33918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-30T10:35:04.191123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.94299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e5e59cd9-e201-44cd-bf0f-48f2f87b1567\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-05-30T10:35:04.191288Z","caller":"traceutil/trace.go:171","msg":"trace[850760755] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e5e59cd9-e201-44cd-bf0f-48f2f87b1567; range_end:; response_count:1; response_revision:24564; }","duration":"868.130054ms","start":"2026-05-30T10:35:03.323129Z","end":"2026-05-30T10:35:04.191259Z","steps":["trace[850760755] 'agreement among raft nodes before linearized reading' (duration: 867.847068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:04.191154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.562599ms","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-30T10:35:04.191406Z","caller":"traceutil/trace.go:171","msg":"trace[755769905] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24564; }","duration":"891.871116ms","start":"2026-05-30T10:35:03.2995Z","end":"2026-05-30T10:35:04.191371Z","steps":["trace[755769905] 'agreement among raft nodes before linearized reading' (duration: 891.481458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:04.19149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:03.299476Z","time spent":"891.986708ms","remote":"127.0.0.1:48486","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":"warn","ts":"2026-05-30T10:35:04.191733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:03.323112Z","time spent":"868.241117ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3787,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e5e59cd9-e201-44cd-bf0f-48f2f87b1567\" "} {"level":"info","ts":"2026-05-30T10:35:05.196786Z","caller":"traceutil/trace.go:171","msg":"trace[1574702174] transaction","detail":"{read_only:false; response_revision:24578; number_of_response:1; }","duration":"468.53352ms","start":"2026-05-30T10:35:04.728238Z","end":"2026-05-30T10:35:05.196771Z","steps":["trace[1574702174] 'process raft request' (duration: 468.414557ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:05.196804Z","caller":"traceutil/trace.go:171","msg":"trace[985967218] linearizableReadLoop","detail":"{readStateIndex:25379; appliedIndex:25379; }","duration":"410.463097ms","start":"2026-05-30T10:35:04.786308Z","end":"2026-05-30T10:35:05.196771Z","steps":["trace[985967218] 'read index received' (duration: 410.447446ms)","trace[985967218] 'applied index is now lower than readState.Index' (duration: 12.711µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:35:05.196934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:04.728211Z","time spent":"468.626182ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T10:35:05.197174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.837525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-30T10:35:05.197236Z","caller":"traceutil/trace.go:171","msg":"trace[284336592] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24578; }","duration":"410.943057ms","start":"2026-05-30T10:35:04.786276Z","end":"2026-05-30T10:35:05.197219Z","steps":["trace[284336592] 'agreement among raft nodes before linearized reading' (duration: 410.659951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:05.197315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:04.786259Z","time spent":"411.033069ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-30T10:35:05.197522Z","caller":"traceutil/trace.go:171","msg":"trace[121113429] transaction","detail":"{read_only:false; response_revision:24579; number_of_response:1; }","duration":"411.122641ms","start":"2026-05-30T10:35:04.786378Z","end":"2026-05-30T10:35:05.1975Z","steps":["trace[121113429] 'process raft request' (duration: 410.936097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:05.197672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:04.786353Z","time spent":"411.242053ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:35:06.616282Z","caller":"traceutil/trace.go:171","msg":"trace[1888300015] transaction","detail":"{read_only:false; response_revision:24594; number_of_response:1; }","duration":"143.355553ms","start":"2026-05-30T10:35:06.47289Z","end":"2026-05-30T10:35:06.616246Z","steps":["trace[1888300015] 'process raft request' (duration: 142.954195ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:09.443137Z","caller":"traceutil/trace.go:171","msg":"trace[2020288062] linearizableReadLoop","detail":"{readStateIndex:25435; appliedIndex:25433; }","duration":"169.036157ms","start":"2026-05-30T10:35:09.274083Z","end":"2026-05-30T10:35:09.443119Z","steps":["trace[2020288062] 'read index received' (duration: 168.950285ms)","trace[2020288062] 'applied index is now lower than readState.Index' (duration: 85.172µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:35:09.443182Z","caller":"traceutil/trace.go:171","msg":"trace[1340958393] transaction","detail":"{read_only:false; response_revision:24632; number_of_response:1; }","duration":"183.47814ms","start":"2026-05-30T10:35:09.259678Z","end":"2026-05-30T10:35:09.443156Z","steps":["trace[1340958393] 'process raft request' (duration: 183.263755ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:09.443273Z","caller":"traceutil/trace.go:171","msg":"trace[432835921] transaction","detail":"{read_only:false; response_revision:24633; number_of_response:1; }","duration":"181.235811ms","start":"2026-05-30T10:35:09.262Z","end":"2026-05-30T10:35:09.443236Z","steps":["trace[432835921] 'process raft request' (duration: 181.077258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:09.443368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.276163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-05-30T10:35:09.443418Z","caller":"traceutil/trace.go:171","msg":"trace[1637832693] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24633; }","duration":"169.390175ms","start":"2026-05-30T10:35:09.274016Z","end":"2026-05-30T10:35:09.443407Z","steps":["trace[1637832693] 'agreement among raft nodes before linearized reading' (duration: 169.218131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:09.478436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.357924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-30T10:35:09.47852Z","caller":"traceutil/trace.go:171","msg":"trace[1640127123] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24634; }","duration":"193.482666ms","start":"2026-05-30T10:35:09.28502Z","end":"2026-05-30T10:35:09.478503Z","steps":["trace[1640127123] 'agreement among raft nodes before linearized reading' (duration: 193.215769ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:44.229407Z","caller":"traceutil/trace.go:171","msg":"trace[988507349] transaction","detail":"{read_only:false; response_revision:24923; number_of_response:1; }","duration":"131.896057ms","start":"2026-05-30T10:35:44.097491Z","end":"2026-05-30T10:35:44.229387Z","steps":["trace[988507349] 'process raft request' (duration: 131.702942ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:44.339336Z","caller":"traceutil/trace.go:171","msg":"trace[1677750307] transaction","detail":"{read_only:false; response_revision:24924; number_of_response:1; }","duration":"241.139393ms","start":"2026-05-30T10:35:44.098149Z","end":"2026-05-30T10:35:44.339288Z","steps":["trace[1677750307] 'process raft request' (duration: 239.268063ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:44.339401Z","caller":"traceutil/trace.go:171","msg":"trace[427335694] transaction","detail":"{read_only:false; response_revision:24925; number_of_response:1; }","duration":"240.815677ms","start":"2026-05-30T10:35:44.09856Z","end":"2026-05-30T10:35:44.339375Z","steps":["trace[427335694] 'process raft request' (duration: 240.625063ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:48.010127Z","caller":"traceutil/trace.go:171","msg":"trace[897472929] linearizableReadLoop","detail":"{readStateIndex:25759; appliedIndex:25758; }","duration":"345.376032ms","start":"2026-05-30T10:35:47.664726Z","end":"2026-05-30T10:35:48.010102Z","steps":["trace[897472929] 'read index received' (duration: 345.223919ms)","trace[897472929] 'applied index is now lower than readState.Index' (duration: 150.943µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:35:48.010415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.669539ms","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-30T10:35:48.010261Z","caller":"traceutil/trace.go:171","msg":"trace[1682057331] transaction","detail":"{read_only:false; response_revision:24949; number_of_response:1; }","duration":"363.248689ms","start":"2026-05-30T10:35:47.64699Z","end":"2026-05-30T10:35:48.010238Z","steps":["trace[1682057331] 'process raft request' (duration: 362.954263ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:48.010484Z","caller":"traceutil/trace.go:171","msg":"trace[335571542] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24949; }","duration":"345.796832ms","start":"2026-05-30T10:35:47.664674Z","end":"2026-05-30T10:35:48.010471Z","steps":["trace[335571542] 'agreement among raft nodes before linearized reading' (duration: 345.588788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:48.01053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:47.664651Z","time spent":"345.870693ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-30T10:35:48.010642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:47.646947Z","time spent":"363.536115ms","remote":"127.0.0.1:48486","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-30T10:35:48.280407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.801845ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-30T10:35:48.280709Z","caller":"traceutil/trace.go:171","msg":"trace[1024168762] transaction","detail":"{read_only:false; response_revision:24951; number_of_response:1; }","duration":"265.914418ms","start":"2026-05-30T10:35:48.014781Z","end":"2026-05-30T10:35:48.280695Z","steps":["trace[1024168762] 'process raft request' (duration: 265.831156ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:48.280702Z","caller":"traceutil/trace.go:171","msg":"trace[37663969] transaction","detail":"{read_only:false; response_revision:24950; number_of_response:1; }","duration":"409.714672ms","start":"2026-05-30T10:35:47.870947Z","end":"2026-05-30T10:35:48.280661Z","steps":["trace[37663969] 'process raft request' (duration: 276.16745ms)","trace[37663969] 'compare' (duration: 132.361596ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:35:48.280774Z","caller":"traceutil/trace.go:171","msg":"trace[2029516744] linearizableReadLoop","detail":"{readStateIndex:25760; appliedIndex:25759; }","duration":"270.526957ms","start":"2026-05-30T10:35:48.010208Z","end":"2026-05-30T10:35:48.280735Z","steps":["trace[2029516744] 'read index received' (duration: 136.923004ms)","trace[2029516744] 'applied index is now lower than readState.Index' (duration: 133.601603ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:35:48.280824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.102896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-30T10:35:48.280854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:47.870921Z","time spent":"409.850195ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:35:48.280874Z","caller":"traceutil/trace.go:171","msg":"trace[932407024] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:24951; }","duration":"395.196468ms","start":"2026-05-30T10:35:47.885654Z","end":"2026-05-30T10:35:48.280851Z","steps":["trace[932407024] 'agreement among raft nodes before linearized reading' (duration: 395.083356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:48.280894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:47.885628Z","time spent":"395.261349ms","remote":"127.0.0.1:33708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-30T10:35:48.280948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.830034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-30T10:35:48.280972Z","caller":"traceutil/trace.go:171","msg":"trace[452367100] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24951; }","duration":"611.904844ms","start":"2026-05-30T10:35:47.669059Z","end":"2026-05-30T10:35:48.280963Z","steps":["trace[452367100] 'agreement among raft nodes before linearized reading' (duration: 611.767481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:48.28099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:47.669038Z","time spent":"611.947906ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-30T10:35:48.280974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.446435ms","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-30T10:35:48.281058Z","caller":"traceutil/trace.go:171","msg":"trace[927734696] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:24951; }","duration":"516.595128ms","start":"2026-05-30T10:35:47.76444Z","end":"2026-05-30T10:35:48.281035Z","steps":["trace[927734696] 'agreement among raft nodes before linearized reading' (duration: 516.437324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:48.281103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:47.764413Z","time spent":"516.68007ms","remote":"127.0.0.1:48644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-05-30T10:35:48.436944Z","caller":"traceutil/trace.go:171","msg":"trace[1857435634] transaction","detail":"{read_only:false; response_revision:24953; number_of_response:1; }","duration":"150.528367ms","start":"2026-05-30T10:35:48.286387Z","end":"2026-05-30T10:35:48.436915Z","steps":["trace[1857435634] 'process raft request' (duration: 126.097981ms)","trace[1857435634] 'compare' (duration: 24.213322ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:35:48.478644Z","caller":"traceutil/trace.go:171","msg":"trace[1462540397] transaction","detail":"{read_only:false; response_revision:24954; number_of_response:1; }","duration":"126.217603ms","start":"2026-05-30T10:35:48.352402Z","end":"2026-05-30T10:35:48.47862Z","steps":["trace[1462540397] 'process raft request' (duration: 126.06244ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:48.478931Z","caller":"traceutil/trace.go:171","msg":"trace[1465865303] transaction","detail":"{read_only:false; response_revision:24955; number_of_response:1; }","duration":"126.416357ms","start":"2026-05-30T10:35:48.352495Z","end":"2026-05-30T10:35:48.478912Z","steps":["trace[1465865303] 'process raft request' (duration: 126.08618ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:48.47921Z","caller":"traceutil/trace.go:171","msg":"trace[1921694356] transaction","detail":"{read_only:false; response_revision:24956; number_of_response:1; }","duration":"125.299464ms","start":"2026-05-30T10:35:48.353873Z","end":"2026-05-30T10:35:48.479172Z","steps":["trace[1921694356] 'process raft request' (duration: 125.17749ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:48.744973Z","caller":"traceutil/trace.go:171","msg":"trace[2081821044] linearizableReadLoop","detail":"{readStateIndex:25767; appliedIndex:25766; }","duration":"265.805325ms","start":"2026-05-30T10:35:48.479142Z","end":"2026-05-30T10:35:48.744948Z","steps":["trace[2081821044] 'read index received' (duration: 179.833841ms)","trace[2081821044] 'applied index is now lower than readState.Index' (duration: 85.970164ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:35:48.74527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.513022ms","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-30T10:35:48.745314Z","caller":"traceutil/trace.go:171","msg":"trace[1299752174] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24957; }","duration":"304.585873ms","start":"2026-05-30T10:35:48.440713Z","end":"2026-05-30T10:35:48.745299Z","steps":["trace[1299752174] 'agreement among raft nodes before linearized reading' (duration: 304.448779ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:48.745285Z","caller":"traceutil/trace.go:171","msg":"trace[1897151877] transaction","detail":"{read_only:false; response_revision:24957; number_of_response:1; }","duration":"276.409546ms","start":"2026-05-30T10:35:48.468827Z","end":"2026-05-30T10:35:48.745237Z","steps":["trace[1897151877] 'process raft request' (duration: 190.115413ms)","trace[1897151877] 'compare' (duration: 85.597847ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:35:48.74532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.554053ms","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-30T10:35:48.74541Z","caller":"traceutil/trace.go:171","msg":"trace[756119354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24957; }","duration":"304.697305ms","start":"2026-05-30T10:35:48.440694Z","end":"2026-05-30T10:35:48.745391Z","steps":["trace[756119354] 'agreement among raft nodes before linearized reading' (duration: 304.424168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:48.745345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:48.440706Z","time spent":"304.631814ms","remote":"127.0.0.1:48486","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":"warn","ts":"2026-05-30T10:35:48.745473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:48.440676Z","time spent":"304.787967ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-30T10:35:48.745583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.863729ms","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-30T10:35:48.745667Z","caller":"traceutil/trace.go:171","msg":"trace[332315610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24957; }","duration":"305.003661ms","start":"2026-05-30T10:35:48.440648Z","end":"2026-05-30T10:35:48.745652Z","steps":["trace[332315610] 'agreement among raft nodes before linearized reading' (duration: 304.545282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:48.745729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:35:48.440627Z","time spent":"305.087264ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T10:35:48.780009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.150242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-30T10:35:48.780127Z","caller":"traceutil/trace.go:171","msg":"trace[391471403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24958; }","duration":"253.323356ms","start":"2026-05-30T10:35:48.526782Z","end":"2026-05-30T10:35:48.780105Z","steps":["trace[391471403] 'agreement among raft nodes before linearized reading' (duration: 252.449258ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:48.780618Z","caller":"traceutil/trace.go:171","msg":"trace[1291307560] transaction","detail":"{read_only:false; response_revision:24958; number_of_response:1; }","duration":"297.272765ms","start":"2026-05-30T10:35:48.483316Z","end":"2026-05-30T10:35:48.780589Z","steps":["trace[1291307560] 'process raft request' (duration: 295.22372ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:48.900428Z","caller":"traceutil/trace.go:171","msg":"trace[817326528] linearizableReadLoop","detail":"{readStateIndex:25771; appliedIndex:25768; }","duration":"106.938938ms","start":"2026-05-30T10:35:48.793468Z","end":"2026-05-30T10:35:48.900407Z","steps":["trace[817326528] 'read index received' (duration: 106.652381ms)","trace[817326528] 'applied index is now lower than readState.Index' (duration: 285.247µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:35:48.900451Z","caller":"traceutil/trace.go:171","msg":"trace[493606396] transaction","detail":"{read_only:false; response_revision:24959; number_of_response:1; }","duration":"149.683881ms","start":"2026-05-30T10:35:48.750724Z","end":"2026-05-30T10:35:48.900408Z","steps":["trace[493606396] 'process raft request' (duration: 149.467096ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:48.900534Z","caller":"traceutil/trace.go:171","msg":"trace[460657170] transaction","detail":"{read_only:false; response_revision:24961; number_of_response:1; }","duration":"149.302771ms","start":"2026-05-30T10:35:48.751221Z","end":"2026-05-30T10:35:48.900524Z","steps":["trace[460657170] 'process raft request' (duration: 149.152278ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:48.900514Z","caller":"traceutil/trace.go:171","msg":"trace[1367418940] transaction","detail":"{read_only:false; response_revision:24960; number_of_response:1; }","duration":"149.702531ms","start":"2026-05-30T10:35:48.750757Z","end":"2026-05-30T10:35:48.900459Z","steps":["trace[1367418940] 'process raft request' (duration: 149.588778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:48.90065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.167212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-30T10:35:48.900678Z","caller":"traceutil/trace.go:171","msg":"trace[385228159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24961; }","duration":"107.238844ms","start":"2026-05-30T10:35:48.793432Z","end":"2026-05-30T10:35:48.900671Z","steps":["trace[385228159] 'agreement among raft nodes before linearized reading' (duration: 107.180273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:49.168479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.858252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-05-30T10:35:49.168572Z","caller":"traceutil/trace.go:171","msg":"trace[1928422078] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:24961; }","duration":"186.981355ms","start":"2026-05-30T10:35:48.981553Z","end":"2026-05-30T10:35:49.168534Z","steps":["trace[1928422078] 'range keys from in-memory index tree' (duration: 186.72857ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:49.365514Z","caller":"traceutil/trace.go:171","msg":"trace[742467265] transaction","detail":"{read_only:false; response_revision:24962; number_of_response:1; }","duration":"132.692423ms","start":"2026-05-30T10:35:49.232792Z","end":"2026-05-30T10:35:49.365484Z","steps":["trace[742467265] 'process raft request' (duration: 74.937427ms)","trace[742467265] 'compare' (duration: 57.118893ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:35:49.739987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.066908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-30T10:35:49.740067Z","caller":"traceutil/trace.go:171","msg":"trace[1616465783] transaction","detail":"{read_only:false; response_revision:24963; number_of_response:1; }","duration":"250.008936ms","start":"2026-05-30T10:35:49.490045Z","end":"2026-05-30T10:35:49.740054Z","steps":["trace[1616465783] 'process raft request' (duration: 93.812635ms)","trace[1616465783] 'compare' (duration: 155.801421ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:35:50.497306Z","caller":"traceutil/trace.go:171","msg":"trace[727137786] transaction","detail":"{read_only:false; response_revision:24965; number_of_response:1; }","duration":"207.267343ms","start":"2026-05-30T10:35:50.290014Z","end":"2026-05-30T10:35:50.497281Z","steps":["trace[727137786] 'process raft request' (duration: 207.11599ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:50.519997Z","caller":"traceutil/trace.go:171","msg":"trace[352019930] transaction","detail":"{read_only:false; response_revision:24966; number_of_response:1; }","duration":"182.051799ms","start":"2026-05-30T10:35:50.337925Z","end":"2026-05-30T10:35:50.519977Z","steps":["trace[352019930] 'process raft request' (duration: 181.874465ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:50.520297Z","caller":"traceutil/trace.go:171","msg":"trace[114184607] linearizableReadLoop","detail":"{readStateIndex:25777; appliedIndex:25776; }","duration":"169.501067ms","start":"2026-05-30T10:35:50.350782Z","end":"2026-05-30T10:35:50.520283Z","steps":["trace[114184607] 'read index received' (duration: 146.560072ms)","trace[114184607] 'applied index is now lower than readState.Index' (duration: 22.938985ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:35:50.520542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.748462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-30T10:35:50.520574Z","caller":"traceutil/trace.go:171","msg":"trace[1822795906] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24969; }","duration":"169.829974ms","start":"2026-05-30T10:35:50.350734Z","end":"2026-05-30T10:35:50.520564Z","steps":["trace[1822795906] 'agreement among raft nodes before linearized reading' (duration: 169.670191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:50.733072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.047873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-30T10:35:50.733161Z","caller":"traceutil/trace.go:171","msg":"trace[1586482079] linearizableReadLoop","detail":"{readStateIndex:25781; appliedIndex:25780; }","duration":"132.154231ms","start":"2026-05-30T10:35:50.600993Z","end":"2026-05-30T10:35:50.733147Z","steps":["trace[1586482079] 'read index received' (duration: 22.969896ms)","trace[1586482079] 'applied index is now lower than readState.Index' (duration: 109.183305ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:35:50.73325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.261604ms","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-30T10:35:50.733218Z","caller":"traceutil/trace.go:171","msg":"trace[498447483] transaction","detail":"{read_only:false; response_revision:24970; number_of_response:1; }","duration":"205.453693ms","start":"2026-05-30T10:35:50.527724Z","end":"2026-05-30T10:35:50.733177Z","steps":["trace[498447483] 'process raft request' (duration: 96.225706ms)","trace[498447483] 'compare' (duration: 108.9222ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:35:50.733273Z","caller":"traceutil/trace.go:171","msg":"trace[920243139] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24970; }","duration":"132.314375ms","start":"2026-05-30T10:35:50.600951Z","end":"2026-05-30T10:35:50.733265Z","steps":["trace[920243139] 'agreement among raft nodes before linearized reading' (duration: 132.266324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:51.032725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.885654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-30T10:35:51.03318Z","caller":"traceutil/trace.go:171","msg":"trace[979075794] transaction","detail":"{read_only:false; response_revision:24971; number_of_response:1; }","duration":"282.997528ms","start":"2026-05-30T10:35:50.750115Z","end":"2026-05-30T10:35:51.033113Z","steps":["trace[979075794] 'process raft request' (duration: 132.586152ms)","trace[979075794] 'compare' (duration: 149.68696ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:35:51.033202Z","caller":"traceutil/trace.go:171","msg":"trace[1827274126] linearizableReadLoop","detail":"{readStateIndex:25782; appliedIndex:25781; }","duration":"280.911822ms","start":"2026-05-30T10:35:50.752263Z","end":"2026-05-30T10:35:51.033175Z","steps":["trace[1827274126] 'read index received' (duration: 130.453855ms)","trace[1827274126] 'applied index is now lower than readState.Index' (duration: 150.455197ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:35:51.033423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.135577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-05-30T10:35:51.033467Z","caller":"traceutil/trace.go:171","msg":"trace[1465046116] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24972; }","duration":"281.214218ms","start":"2026-05-30T10:35:50.752242Z","end":"2026-05-30T10:35:51.033456Z","steps":["trace[1465046116] 'agreement among raft nodes before linearized reading' (duration: 281.005874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:51.033586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.54922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-30T10:35:51.033654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.8019ms","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-30T10:35:51.033685Z","caller":"traceutil/trace.go:171","msg":"trace[2098609985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24972; }","duration":"128.962683ms","start":"2026-05-30T10:35:50.904713Z","end":"2026-05-30T10:35:51.033676Z","steps":["trace[2098609985] 'agreement among raft nodes before linearized reading' (duration: 128.82868ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:51.033675Z","caller":"traceutil/trace.go:171","msg":"trace[462218507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24972; }","duration":"240.673864ms","start":"2026-05-30T10:35:50.792978Z","end":"2026-05-30T10:35:51.033652Z","steps":["trace[462218507] 'agreement among raft nodes before linearized reading' (duration: 240.457499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:51.033691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.129886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-30T10:35:51.033591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.444831ms","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-30T10:35:51.03377Z","caller":"traceutil/trace.go:171","msg":"trace[1153519268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24972; }","duration":"128.655065ms","start":"2026-05-30T10:35:50.905094Z","end":"2026-05-30T10:35:51.033749Z","steps":["trace[1153519268] 'agreement among raft nodes before linearized reading' (duration: 128.351089ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:51.033724Z","caller":"traceutil/trace.go:171","msg":"trace[792870960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24972; }","duration":"129.216308ms","start":"2026-05-30T10:35:50.904499Z","end":"2026-05-30T10:35:51.033715Z","steps":["trace[792870960] 'agreement among raft nodes before linearized reading' (duration: 129.106386ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:51.033351Z","caller":"traceutil/trace.go:171","msg":"trace[1301373352] transaction","detail":"{read_only:false; response_revision:24972; number_of_response:1; }","duration":"151.996ms","start":"2026-05-30T10:35:50.88125Z","end":"2026-05-30T10:35:51.033246Z","steps":["trace[1301373352] 'process raft request' (duration: 151.869008ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:51.248711Z","caller":"traceutil/trace.go:171","msg":"trace[697500333] transaction","detail":"{read_only:false; response_revision:24973; number_of_response:1; }","duration":"211.424331ms","start":"2026-05-30T10:35:51.037257Z","end":"2026-05-30T10:35:51.248682Z","steps":["trace[697500333] 'process raft request' (duration: 193.365312ms)","trace[697500333] 'compare' (duration: 17.848135ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:35:51.319789Z","caller":"traceutil/trace.go:171","msg":"trace[341179855] linearizableReadLoop","detail":"{readStateIndex:25787; appliedIndex:25783; }","duration":"261.593806ms","start":"2026-05-30T10:35:51.058176Z","end":"2026-05-30T10:35:51.31977Z","steps":["trace[341179855] 'read index received' (duration: 172.465812ms)","trace[341179855] 'applied index is now lower than readState.Index' (duration: 89.126774ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:35:51.319852Z","caller":"traceutil/trace.go:171","msg":"trace[1365788348] transaction","detail":"{read_only:false; response_revision:24975; number_of_response:1; }","duration":"282.295232ms","start":"2026-05-30T10:35:51.03755Z","end":"2026-05-30T10:35:51.319845Z","steps":["trace[1365788348] 'process raft request' (duration: 282.162359ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:51.3199Z","caller":"traceutil/trace.go:171","msg":"trace[201284540] transaction","detail":"{read_only:false; response_revision:24976; number_of_response:1; }","duration":"282.220031ms","start":"2026-05-30T10:35:51.037664Z","end":"2026-05-30T10:35:51.319884Z","steps":["trace[201284540] 'process raft request' (duration: 282.078168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:51.320004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.853398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-30T10:35:51.320003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.814331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-30T10:35:51.320062Z","caller":"traceutil/trace.go:171","msg":"trace[790498622] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:24976; }","duration":"261.935413ms","start":"2026-05-30T10:35:51.058117Z","end":"2026-05-30T10:35:51.320053Z","steps":["trace[790498622] 'agreement among raft nodes before linearized reading' (duration: 261.817641ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:51.32003Z","caller":"traceutil/trace.go:171","msg":"trace[2120078938] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24976; }","duration":"215.927449ms","start":"2026-05-30T10:35:51.104094Z","end":"2026-05-30T10:35:51.320021Z","steps":["trace[2120078938] 'agreement among raft nodes before linearized reading' (duration: 215.873648ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:51.319806Z","caller":"traceutil/trace.go:171","msg":"trace[1167542525] transaction","detail":"{read_only:false; response_revision:24974; number_of_response:1; }","duration":"282.332052ms","start":"2026-05-30T10:35:51.037454Z","end":"2026-05-30T10:35:51.319786Z","steps":["trace[1167542525] 'process raft request' (duration: 282.107037ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:52.647435Z","caller":"traceutil/trace.go:171","msg":"trace[1882430239] transaction","detail":"{read_only:false; response_revision:24981; number_of_response:1; }","duration":"118.043327ms","start":"2026-05-30T10:35:52.52937Z","end":"2026-05-30T10:35:52.647413Z","steps":["trace[1882430239] 'process raft request' (duration: 117.943885ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:52.647454Z","caller":"traceutil/trace.go:171","msg":"trace[1681983927] transaction","detail":"{read_only:false; response_revision:24980; number_of_response:1; }","duration":"119.060349ms","start":"2026-05-30T10:35:52.528355Z","end":"2026-05-30T10:35:52.647415Z","steps":["trace[1681983927] 'process raft request' (duration: 80.407925ms)","trace[1681983927] 'compare' (duration: 38.372938ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:35:52.697079Z","caller":"traceutil/trace.go:171","msg":"trace[1534928896] linearizableReadLoop","detail":"{readStateIndex:25794; appliedIndex:25790; }","duration":"165.295557ms","start":"2026-05-30T10:35:52.531764Z","end":"2026-05-30T10:35:52.69706Z","steps":["trace[1534928896] 'read index received' (duration: 77.082274ms)","trace[1534928896] 'applied index is now lower than readState.Index' (duration: 88.212433ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:35:52.697219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.43146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-30T10:35:52.697249Z","caller":"traceutil/trace.go:171","msg":"trace[139076591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24983; }","duration":"165.500081ms","start":"2026-05-30T10:35:52.531741Z","end":"2026-05-30T10:35:52.697242Z","steps":["trace[139076591] 'agreement among raft nodes before linearized reading' (duration: 165.402919ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:52.697585Z","caller":"traceutil/trace.go:171","msg":"trace[1258976356] transaction","detail":"{read_only:false; response_revision:24982; number_of_response:1; }","duration":"166.85258ms","start":"2026-05-30T10:35:52.530722Z","end":"2026-05-30T10:35:52.697575Z","steps":["trace[1258976356] 'process raft request' (duration: 166.175646ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:52.697708Z","caller":"traceutil/trace.go:171","msg":"trace[1948384983] transaction","detail":"{read_only:false; response_revision:24983; number_of_response:1; }","duration":"166.682327ms","start":"2026-05-30T10:35:52.531018Z","end":"2026-05-30T10:35:52.697701Z","steps":["trace[1948384983] 'process raft request' (duration: 166.003333ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:52.88181Z","caller":"traceutil/trace.go:171","msg":"trace[644880309] transaction","detail":"{read_only:false; response_revision:24984; number_of_response:1; }","duration":"140.134014ms","start":"2026-05-30T10:35:52.74165Z","end":"2026-05-30T10:35:52.881784Z","steps":["trace[644880309] 'process raft request' (duration: 47.535625ms)","trace[644880309] 'compare' (duration: 92.367164ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:35:53.140772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.903852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-30T10:35:53.140852Z","caller":"traceutil/trace.go:171","msg":"trace[1507528116] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:24984; }","duration":"129.047505ms","start":"2026-05-30T10:35:53.011786Z","end":"2026-05-30T10:35:53.140834Z","steps":["trace[1507528116] 'count revisions from in-memory index tree' (duration: 128.769758ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:53.141403Z","caller":"traceutil/trace.go:171","msg":"trace[457241812] transaction","detail":"{read_only:false; response_revision:24985; number_of_response:1; }","duration":"102.918291ms","start":"2026-05-30T10:35:53.038454Z","end":"2026-05-30T10:35:53.141373Z","steps":["trace[457241812] 'process raft request' (duration: 72.228439ms)","trace[457241812] 'compare' (duration: 30.150051ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:35:53.528383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.121721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-30T10:35:53.528528Z","caller":"traceutil/trace.go:171","msg":"trace[1636217793] transaction","detail":"{read_only:false; response_revision:24988; number_of_response:1; }","duration":"199.883373ms","start":"2026-05-30T10:35:53.328628Z","end":"2026-05-30T10:35:53.528511Z","steps":["trace[1636217793] 'process raft request' (duration: 199.838882ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:53.528537Z","caller":"traceutil/trace.go:171","msg":"trace[2129645032] linearizableReadLoop","detail":"{readStateIndex:25798; appliedIndex:25797; }","duration":"204.38595ms","start":"2026-05-30T10:35:53.324134Z","end":"2026-05-30T10:35:53.52852Z","steps":["trace[2129645032] 'read index received' (duration: 104.112376ms)","trace[2129645032] 'applied index is now lower than readState.Index' (duration: 100.271394ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:35:53.528518Z","caller":"traceutil/trace.go:171","msg":"trace[887710947] transaction","detail":"{read_only:false; response_revision:24987; number_of_response:1; }","duration":"204.705208ms","start":"2026-05-30T10:35:53.323778Z","end":"2026-05-30T10:35:53.528484Z","steps":["trace[887710947] 'process raft request' (duration: 104.425683ms)","trace[887710947] 'compare' (duration: 99.968658ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:35:53.528681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.545835ms","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-30T10:35:53.528717Z","caller":"traceutil/trace.go:171","msg":"trace[1096827146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24988; }","duration":"204.626476ms","start":"2026-05-30T10:35:53.324082Z","end":"2026-05-30T10:35:53.528708Z","steps":["trace[1096827146] 'agreement among raft nodes before linearized reading' (duration: 204.508384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:53.528717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.459042ms","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-30T10:35:53.528744Z","caller":"traceutil/trace.go:171","msg":"trace[273571033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24988; }","duration":"204.513154ms","start":"2026-05-30T10:35:53.324224Z","end":"2026-05-30T10:35:53.528738Z","steps":["trace[273571033] 'agreement among raft nodes before linearized reading' (duration: 204.388301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:53.528794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.109262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-05-30T10:35:53.528852Z","caller":"traceutil/trace.go:171","msg":"trace[1366066854] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24988; }","duration":"202.216124ms","start":"2026-05-30T10:35:53.326623Z","end":"2026-05-30T10:35:53.528839Z","steps":["trace[1366066854] 'agreement among raft nodes before linearized reading' (duration: 202.03302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:35:53.792948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.22722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-30T10:35:53.79319Z","caller":"traceutil/trace.go:171","msg":"trace[41092761] transaction","detail":"{read_only:false; response_revision:24990; number_of_response:1; }","duration":"261.709707ms","start":"2026-05-30T10:35:53.53146Z","end":"2026-05-30T10:35:53.79317Z","steps":["trace[41092761] 'process raft request' (duration: 112.190501ms)","trace[41092761] 'compare' (duration: 148.925523ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:35:54.259344Z","caller":"traceutil/trace.go:171","msg":"trace[1350156732] transaction","detail":"{read_only:false; response_revision:24992; number_of_response:1; }","duration":"170.113691ms","start":"2026-05-30T10:35:54.089205Z","end":"2026-05-30T10:35:54.259319Z","steps":["trace[1350156732] 'process raft request' (duration: 169.951728ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:35:55.00567Z","caller":"traceutil/trace.go:171","msg":"trace[768876185] transaction","detail":"{read_only:false; response_revision:24998; number_of_response:1; }","duration":"105.68717ms","start":"2026-05-30T10:35:54.899958Z","end":"2026-05-30T10:35:55.005645Z","steps":["trace[768876185] 'process raft request' (duration: 105.534397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:36:04.547469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.781913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-30T10:36:04.547584Z","caller":"traceutil/trace.go:171","msg":"trace[173148907] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:25065; }","duration":"112.978788ms","start":"2026-05-30T10:36:04.434575Z","end":"2026-05-30T10:36:04.547554Z","steps":["trace[173148907] 'count revisions from in-memory index tree' (duration: 112.63614ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:36:04.919814Z","caller":"traceutil/trace.go:171","msg":"trace[1440499490] linearizableReadLoop","detail":"{readStateIndex:25879; appliedIndex:25878; }","duration":"252.475018ms","start":"2026-05-30T10:36:04.667315Z","end":"2026-05-30T10:36:04.91979Z","steps":["trace[1440499490] 'read index received' (duration: 252.352955ms)","trace[1440499490] 'applied index is now lower than readState.Index' (duration: 120.793µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:36:04.91984Z","caller":"traceutil/trace.go:171","msg":"trace[1958889812] transaction","detail":"{read_only:false; response_revision:25066; number_of_response:1; }","duration":"331.190516ms","start":"2026-05-30T10:36:04.588621Z","end":"2026-05-30T10:36:04.919812Z","steps":["trace[1958889812] 'process raft request' (duration: 330.984182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:36:04.919962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.622181ms","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-30T10:36:04.919996Z","caller":"traceutil/trace.go:171","msg":"trace[1478944960] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:25066; }","duration":"252.710563ms","start":"2026-05-30T10:36:04.667278Z","end":"2026-05-30T10:36:04.919989Z","steps":["trace[1478944960] 'agreement among raft nodes before linearized reading' (duration: 252.612561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:36:04.920023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:36:04.588599Z","time spent":"331.32582ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T10:36:05.533843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.096305ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-30T10:36:05.534185Z","caller":"traceutil/trace.go:171","msg":"trace[779529307] transaction","detail":"{read_only:false; response_revision:25067; number_of_response:1; }","duration":"848.456489ms","start":"2026-05-30T10:36:04.685709Z","end":"2026-05-30T10:36:05.534165Z","steps":["trace[779529307] 'process raft request' (duration: 848.218274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:36:05.53427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:36:04.685684Z","time spent":"848.53056ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:36:05.53445Z","caller":"traceutil/trace.go:171","msg":"trace[168595502] transaction","detail":"{read_only:false; response_revision:25068; number_of_response:1; }","duration":"842.554042ms","start":"2026-05-30T10:36:04.691882Z","end":"2026-05-30T10:36:05.534436Z","steps":["trace[168595502] 'process raft request' (duration: 842.151883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:36:05.534503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:36:04.691854Z","time spent":"842.624113ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:36:05.534928Z","caller":"traceutil/trace.go:171","msg":"trace[1818536641] transaction","detail":"{read_only:false; response_revision:25070; number_of_response:1; }","duration":"799.664577ms","start":"2026-05-30T10:36:04.73523Z","end":"2026-05-30T10:36:05.534895Z","steps":["trace[1818536641] 'process raft request' (duration: 799.572845ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:36:05.534997Z","caller":"traceutil/trace.go:171","msg":"trace[899226930] linearizableReadLoop","detail":"{readStateIndex:25884; appliedIndex:25879; }","duration":"484.77129ms","start":"2026-05-30T10:36:05.050196Z","end":"2026-05-30T10:36:05.534968Z","steps":["trace[899226930] 'read index received' (duration: 304.637794ms)","trace[899226930] 'applied index is now lower than readState.Index' (duration: 180.129556ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:36:05.535034Z","caller":"traceutil/trace.go:171","msg":"trace[1145353719] transaction","detail":"{read_only:false; response_revision:25069; number_of_response:1; }","duration":"801.871763ms","start":"2026-05-30T10:36:04.733153Z","end":"2026-05-30T10:36:05.535025Z","steps":["trace[1145353719] 'process raft request' (duration: 801.23047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:36:05.535056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:36:04.73521Z","time spent":"799.77836ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T10:36:05.535099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:36:04.733127Z","time spent":"801.932465ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T10:36:05.535229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.986286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-30T10:36:05.535309Z","caller":"traceutil/trace.go:171","msg":"trace[1430357883] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25070; }","duration":"485.134378ms","start":"2026-05-30T10:36:05.050156Z","end":"2026-05-30T10:36:05.53529Z","steps":["trace[1430357883] 'agreement among raft nodes before linearized reading' (duration: 484.887803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:36:05.535351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:36:05.05014Z","time spent":"485.20079ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-30T10:36:05.535527Z","caller":"traceutil/trace.go:171","msg":"trace[577032173] transaction","detail":"{read_only:false; response_revision:25071; number_of_response:1; }","duration":"228.442799ms","start":"2026-05-30T10:36:05.307059Z","end":"2026-05-30T10:36:05.535502Z","steps":["trace[577032173] 'process raft request' (duration: 228.109251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:36:05.536023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.098896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-05-30T10:36:05.536051Z","caller":"traceutil/trace.go:171","msg":"trace[1844971048] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:25071; }","duration":"484.157968ms","start":"2026-05-30T10:36:05.051883Z","end":"2026-05-30T10:36:05.536041Z","steps":["trace[1844971048] 'agreement among raft nodes before linearized reading' (duration: 483.560945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:36:05.536076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:36:05.051865Z","time spent":"484.199519ms","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1657,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"info","ts":"2026-05-30T10:36:05.997308Z","caller":"traceutil/trace.go:171","msg":"trace[2098694890] linearizableReadLoop","detail":"{readStateIndex:25886; appliedIndex:25885; }","duration":"457.504293ms","start":"2026-05-30T10:36:05.539787Z","end":"2026-05-30T10:36:05.997291Z","steps":["trace[2098694890] 'read index received' (duration: 457.388921ms)","trace[2098694890] 'applied index is now lower than readState.Index' (duration: 114.532µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:36:05.997374Z","caller":"traceutil/trace.go:171","msg":"trace[1593468086] transaction","detail":"{read_only:false; response_revision:25072; number_of_response:1; }","duration":"458.572096ms","start":"2026-05-30T10:36:05.538774Z","end":"2026-05-30T10:36:05.997346Z","steps":["trace[1593468086] 'process raft request' (duration: 458.382462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:36:05.997464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.674326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-60866ab4-cd1e-4dee-8fee-e27a85af3fba\" ","response":"range_response_count:1 size:1294"} {"level":"info","ts":"2026-05-30T10:36:05.997496Z","caller":"traceutil/trace.go:171","msg":"trace[773868254] range","detail":"{range_begin:/registry/persistentvolumes/pvc-60866ab4-cd1e-4dee-8fee-e27a85af3fba; range_end:; response_count:1; response_revision:25072; }","duration":"457.765888ms","start":"2026-05-30T10:36:05.53972Z","end":"2026-05-30T10:36:05.997486Z","steps":["trace[773868254] 'agreement among raft nodes before linearized reading' (duration: 457.651206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:36:05.997517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:36:05.539701Z","time spent":"457.811329ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1317,"request content":"key:\"/registry/persistentvolumes/pvc-60866ab4-cd1e-4dee-8fee-e27a85af3fba\" "} {"level":"warn","ts":"2026-05-30T10:36:05.997515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:36:05.538759Z","time spent":"458.673338ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:36:06.143818Z","caller":"traceutil/trace.go:171","msg":"trace[438492547] transaction","detail":"{read_only:false; response_revision:25073; number_of_response:1; }","duration":"211.155936ms","start":"2026-05-30T10:36:05.932603Z","end":"2026-05-30T10:36:06.143759Z","steps":["trace[438492547] 'process raft request' (duration: 139.761506ms)","trace[438492547] 'compare' (duration: 71.245017ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:36:06.143956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.209211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-30T10:36:06.14384Z","caller":"traceutil/trace.go:171","msg":"trace[532128480] linearizableReadLoop","detail":"{readStateIndex:25887; appliedIndex:25886; }","duration":"146.465731ms","start":"2026-05-30T10:36:05.997354Z","end":"2026-05-30T10:36:06.14382Z","steps":["trace[532128480] 'read index received' (duration: 75.008169ms)","trace[532128480] 'applied index is now lower than readState.Index' (duration: 71.455982ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:36:06.143983Z","caller":"traceutil/trace.go:171","msg":"trace[1141181735] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25074; }","duration":"568.282683ms","start":"2026-05-30T10:36:05.575694Z","end":"2026-05-30T10:36:06.143977Z","steps":["trace[1141181735] 'agreement among raft nodes before linearized reading' (duration: 568.179321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:36:06.143983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.470524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-30T10:36:06.144026Z","caller":"traceutil/trace.go:171","msg":"trace[1624247856] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:25074; }","duration":"319.558966ms","start":"2026-05-30T10:36:05.824456Z","end":"2026-05-30T10:36:06.144015Z","steps":["trace[1624247856] 'agreement among raft nodes before linearized reading' (duration: 319.438133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:36:06.14405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.730058ms","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":"warn","ts":"2026-05-30T10:36:06.144056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:36:05.824437Z","time spent":"319.610058ms","remote":"127.0.0.1:48736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":59,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-05-30T10:36:06.144063Z","caller":"traceutil/trace.go:171","msg":"trace[531253405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25074; }","duration":"565.742808ms","start":"2026-05-30T10:36:05.578316Z","end":"2026-05-30T10:36:06.144058Z","steps":["trace[531253405] 'agreement among raft nodes before linearized reading' (duration: 565.693727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:36:06.144078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:36:05.57827Z","time spent":"565.804569ms","remote":"127.0.0.1:48486","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":"warn","ts":"2026-05-30T10:36:06.144088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.434535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-30T10:36:06.144112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.960228ms","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-30T10:36:06.144153Z","caller":"traceutil/trace.go:171","msg":"trace[936777270] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25074; }","duration":"200.546437ms","start":"2026-05-30T10:36:05.943592Z","end":"2026-05-30T10:36:06.144138Z","steps":["trace[936777270] 'agreement among raft nodes before linearized reading' (duration: 200.416695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:36:06.144169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.432337ms","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-30T10:36:06.144173Z","caller":"traceutil/trace.go:171","msg":"trace[752305926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25074; }","duration":"487.026499ms","start":"2026-05-30T10:36:05.657131Z","end":"2026-05-30T10:36:06.144157Z","steps":["trace[752305926] 'agreement among raft nodes before linearized reading' (duration: 486.882146ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:36:06.144184Z","caller":"traceutil/trace.go:171","msg":"trace[1990900701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25074; }","duration":"303.473478ms","start":"2026-05-30T10:36:05.840706Z","end":"2026-05-30T10:36:06.14418Z","steps":["trace[1990900701] 'agreement among raft nodes before linearized reading' (duration: 303.429737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:36:06.144199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:36:05.840692Z","time spent":"303.504569ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-30T10:36:06.144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:36:05.575675Z","time spent":"568.320344ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-30T10:36:06.144203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:36:05.657078Z","time spent":"487.119041ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T10:36:06.144203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.328327ms","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-30T10:36:06.144284Z","caller":"traceutil/trace.go:171","msg":"trace[534346573] 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:25074; }","duration":"418.449469ms","start":"2026-05-30T10:36:05.725807Z","end":"2026-05-30T10:36:06.144256Z","steps":["trace[534346573] 'agreement among raft nodes before linearized reading' (duration: 418.320976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:36:06.144327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.969051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-30T10:36:06.144327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:36:05.725786Z","time spent":"418.530731ms","remote":"127.0.0.1:33556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true "} {"level":"info","ts":"2026-05-30T10:36:06.144362Z","caller":"traceutil/trace.go:171","msg":"trace[721030098] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:25074; }","duration":"191.031022ms","start":"2026-05-30T10:36:05.953319Z","end":"2026-05-30T10:36:06.14435Z","steps":["trace[721030098] 'agreement among raft nodes before linearized reading' (duration: 190.978171ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:36:06.475782Z","caller":"traceutil/trace.go:171","msg":"trace[2097887664] linearizableReadLoop","detail":"{readStateIndex:25892; appliedIndex:25889; }","duration":"224.454113ms","start":"2026-05-30T10:36:06.251303Z","end":"2026-05-30T10:36:06.475757Z","steps":["trace[2097887664] 'read index received' (duration: 224.260289ms)","trace[2097887664] 'applied index is now lower than readState.Index' (duration: 192.804µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:36:06.476023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.703688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-30T10:36:06.476008Z","caller":"traceutil/trace.go:171","msg":"trace[67511783] transaction","detail":"{read_only:false; response_revision:25076; number_of_response:1; }","duration":"328.322214ms","start":"2026-05-30T10:36:06.147643Z","end":"2026-05-30T10:36:06.475965Z","steps":["trace[67511783] 'process raft request' (duration: 327.850124ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:36:06.476066Z","caller":"traceutil/trace.go:171","msg":"trace[638227119] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25078; }","duration":"224.79474ms","start":"2026-05-30T10:36:06.251261Z","end":"2026-05-30T10:36:06.476055Z","steps":["trace[638227119] 'agreement among raft nodes before linearized reading' (duration: 224.604506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:36:06.476167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:36:06.147623Z","time spent":"328.461148ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:36:06.476144Z","caller":"traceutil/trace.go:171","msg":"trace[2086511798] transaction","detail":"{read_only:false; response_revision:25077; number_of_response:1; }","duration":"328.09704ms","start":"2026-05-30T10:36:06.148017Z","end":"2026-05-30T10:36:06.476114Z","steps":["trace[2086511798] 'process raft request' (duration: 327.6532ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:36:06.47618Z","caller":"traceutil/trace.go:171","msg":"trace[1240137540] transaction","detail":"{read_only:false; response_revision:25078; number_of_response:1; }","duration":"327.614069ms","start":"2026-05-30T10:36:06.148529Z","end":"2026-05-30T10:36:06.476143Z","steps":["trace[1240137540] 'process raft request' (duration: 327.18991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:36:06.476418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:36:06.14852Z","time spent":"327.825474ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T10:36:06.476392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:36:06.148009Z","time spent":"328.265733ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:36:06.500209Z","caller":"traceutil/trace.go:171","msg":"trace[327260453] transaction","detail":"{read_only:false; response_revision:25079; number_of_response:1; }","duration":"188.035037ms","start":"2026-05-30T10:36:06.312148Z","end":"2026-05-30T10:36:06.500183Z","steps":["trace[327260453] 'process raft request' (duration: 187.522416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:36:06.65175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.002252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-30T10:36:06.651873Z","caller":"traceutil/trace.go:171","msg":"trace[454040973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25080; }","duration":"128.171736ms","start":"2026-05-30T10:36:06.523666Z","end":"2026-05-30T10:36:06.651838Z","steps":["trace[454040973] 'range keys from in-memory index tree' (duration: 127.90414ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:37:08.4901Z","caller":"traceutil/trace.go:171","msg":"trace[455344812] transaction","detail":"{read_only:false; response_revision:25713; number_of_response:1; }","duration":"125.207123ms","start":"2026-05-30T10:37:08.364871Z","end":"2026-05-30T10:37:08.490078Z","steps":["trace[455344812] 'process raft request' (duration: 125.034179ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:38:06.044961Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23410} {"level":"info","ts":"2026-05-30T10:38:06.180923Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23410,"took":"131.60482ms","hash":1410863286,"current-db-size-bytes":60395520,"current-db-size":"60 MB","current-db-size-in-use-bytes":36511744,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-30T10:38:06.181057Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1410863286,"revision":23410,"compact-revision":20304} {"level":"info","ts":"2026-05-30T10:38:28.395917Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a92.wal"} {"level":"info","ts":"2026-05-30T10:39:43.432263Z","caller":"traceutil/trace.go:171","msg":"trace[1676793320] transaction","detail":"{read_only:false; response_revision:27284; number_of_response:1; }","duration":"148.555443ms","start":"2026-05-30T10:39:43.283666Z","end":"2026-05-30T10:39:43.432222Z","steps":["trace[1676793320] 'process raft request' (duration: 148.298868ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:39:43.441703Z","caller":"traceutil/trace.go:171","msg":"trace[2082192607] transaction","detail":"{read_only:false; response_revision:27285; number_of_response:1; }","duration":"155.001863ms","start":"2026-05-30T10:39:43.286683Z","end":"2026-05-30T10:39:43.441685Z","steps":["trace[2082192607] 'process raft request' (duration: 154.523993ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:43:06.05015Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26256} {"level":"info","ts":"2026-05-30T10:43:06.196523Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26256,"took":"135.521421ms","hash":1614946783,"current-db-size-bytes":60395520,"current-db-size":"60 MB","current-db-size-in-use-bytes":33173504,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-30T10:43:06.196583Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1614946783,"revision":26256,"compact-revision":23410} {"level":"info","ts":"2026-05-30T10:43:40.899377Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"35b2766da46a76bb","local-member-applied-index":30004,"local-member-snapshot-index":20003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-30T10:43:40.905097Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-05-30T10:43:40.905284Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-05-30T10:48:06.05624Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28826} {"level":"info","ts":"2026-05-30T10:48:06.19307Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28826,"took":"131.745439ms","hash":2582823141,"current-db-size-bytes":60395520,"current-db-size":"60 MB","current-db-size-in-use-bytes":30064640,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-30T10:48:06.193131Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2582823141,"revision":28826,"compact-revision":26256} {"level":"info","ts":"2026-05-30T10:53:06.064215Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31043} {"level":"info","ts":"2026-05-30T10:53:06.182368Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31043,"took":"114.179981ms","hash":1839931784,"current-db-size-bytes":60395520,"current-db-size":"60 MB","current-db-size-in-use-bytes":30945280,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-30T10:53:06.182461Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1839931784,"revision":31043,"compact-revision":28826} {"level":"info","ts":"2026-05-30T10:58:06.070467Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33313} {"level":"info","ts":"2026-05-30T10:58:06.229491Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33313,"took":"154.584042ms","hash":1167486841,"current-db-size-bytes":61435904,"current-db-size":"61 MB","current-db-size-in-use-bytes":32403456,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-30T10:58:06.229572Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1167486841,"revision":33313,"compact-revision":31043} {"level":"info","ts":"2026-05-30T11:03:06.076081Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35607} {"level":"info","ts":"2026-05-30T11:03:06.223729Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35607,"took":"142.213854ms","hash":1542242622,"current-db-size-bytes":62279680,"current-db-size":"62 MB","current-db-size-in-use-bytes":31268864,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-30T11:03:06.223792Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1542242622,"revision":35607,"compact-revision":33313} {"level":"info","ts":"2026-05-30T11:05:00.137075Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"35b2766da46a76bb","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-30T11:05:00.142121Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-05-30T11:05:00.142195Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005}