{"level":"warn","ts":"2026-09-22T02:02:51.15551Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-09-22T02:02:51.155707Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.212: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://162.253.55.212:2380","--initial-cluster=instance=https://162.253.55.212:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.212:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.212: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-09-22T02:02:51.155843Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-09-22T02:02:51.155865Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.212:2380"]}
{"level":"info","ts":"2026-09-22T02:02:51.155904Z","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-09-22T02:02:51.15713Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"]}
{"level":"info","ts":"2026-09-22T02:02:51.157273Z","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://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.212: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-09-22T02:02:51.162792Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.118709ms"}
{"level":"info","ts":"2026-09-22T02:02:51.168903Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d078b1e39c21939c","cluster-id":"cdc9ba8de57e3589"}
{"level":"info","ts":"2026-09-22T02:02:51.169035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=()"}
{"level":"info","ts":"2026-09-22T02:02:51.169069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 0"}
{"level":"info","ts":"2026-09-22T02:02:51.169096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d078b1e39c21939c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-09-22T02:02:51.169109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 1"}
{"level":"info","ts":"2026-09-22T02:02:51.169175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"}
{"level":"warn","ts":"2026-09-22T02:02:51.176207Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-09-22T02:02:51.178592Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-09-22T02:02:51.180387Z","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-09-22T02:02:51.184552Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d078b1e39c21939c","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-09-22T02:02:51.184797Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d078b1e39c21939c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-09-22T02:02:51.184943Z","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-09-22T02:02:51.185222Z","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-09-22T02:02:51.185376Z","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-09-22T02:02:51.187114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"}
{"level":"info","ts":"2026-09-22T02:02:51.188015Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","added-peer-id":"d078b1e39c21939c","added-peer-peer-urls":["https://162.253.55.212:2380"]}
{"level":"info","ts":"2026-09-22T02:02:51.190845Z","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-09-22T02:02:51.191063Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.212:2380"}
{"level":"info","ts":"2026-09-22T02:02:51.191139Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.212:2380"}
{"level":"info","ts":"2026-09-22T02:02:51.191181Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d078b1e39c21939c","initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-09-22T02:02:51.191277Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-09-22T02:02:51.969855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c is starting a new election at term 1"}
{"level":"info","ts":"2026-09-22T02:02:51.969947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became pre-candidate at term 1"}
{"level":"info","ts":"2026-09-22T02:02:51.970008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgPreVoteResp from d078b1e39c21939c at term 1"}
{"level":"info","ts":"2026-09-22T02:02:51.97003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became candidate at term 2"}
{"level":"info","ts":"2026-09-22T02:02:51.970042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgVoteResp from d078b1e39c21939c at term 2"}
{"level":"info","ts":"2026-09-22T02:02:51.970057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became leader at term 2"}
{"level":"info","ts":"2026-09-22T02:02:51.970072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d078b1e39c21939c elected leader d078b1e39c21939c at term 2"}
{"level":"info","ts":"2026-09-22T02:02:51.971422Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-22T02:02:51.972778Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-09-22T02:02:51.972787Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d078b1e39c21939c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.212:2379]}","request-path":"/0/members/d078b1e39c21939c/attributes","cluster-id":"cdc9ba8de57e3589","publish-timeout":"7s"}
{"level":"info","ts":"2026-09-22T02:02:51.972925Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-09-22T02:02:51.973213Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-09-22T02:02:51.973279Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-09-22T02:02:51.973635Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-22T02:02:51.97385Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-22T02:02:51.973914Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-22T02:02:51.977064Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.212:2379"}
{"level":"info","ts":"2026-09-22T02:02:51.977496Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-09-22T02:03:32.641684Z","caller":"traceutil/trace.go:171","msg":"trace[111128000] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"119.510711ms","start":"2026-09-22T02:03:32.522144Z","end":"2026-09-22T02:03:32.641655Z","steps":["trace[111128000] 'process raft request'  (duration: 119.277196ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:03:34.447191Z","caller":"traceutil/trace.go:171","msg":"trace[1300844886] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"119.215434ms","start":"2026-09-22T02:03:34.327951Z","end":"2026-09-22T02:03:34.447166Z","steps":["trace[1300844886] 'read index received'  (duration: 117.662251ms)","trace[1300844886] 'applied index is now lower than readState.Index'  (duration: 1.552033ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:03:34.447329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.362838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-22T02:03:34.447307Z","caller":"traceutil/trace.go:171","msg":"trace[1078847710] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"188.055014ms","start":"2026-09-22T02:03:34.259217Z","end":"2026-09-22T02:03:34.447272Z","steps":["trace[1078847710] 'process raft request'  (duration: 186.764686ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:03:34.447403Z","caller":"traceutil/trace.go:171","msg":"trace[1912815411] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:641; }","duration":"119.50379ms","start":"2026-09-22T02:03:34.327887Z","end":"2026-09-22T02:03:34.447391Z","steps":["trace[1912815411] 'agreement among raft nodes before linearized reading'  (duration: 119.384328ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:03:34.462376Z","caller":"traceutil/trace.go:171","msg":"trace[1160660637] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"109.28442ms","start":"2026-09-22T02:03:34.353064Z","end":"2026-09-22T02:03:34.462349Z","steps":["trace[1160660637] 'process raft request'  (duration: 109.014354ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:03:42.058275Z","caller":"traceutil/trace.go:171","msg":"trace[399271436] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"280.241836ms","start":"2026-09-22T02:03:41.777983Z","end":"2026-09-22T02:03:42.058225Z","steps":["trace[399271436] 'process raft request'  (duration: 279.92164ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:03:58.984595Z","caller":"traceutil/trace.go:171","msg":"trace[1149421531] linearizableReadLoop","detail":"{readStateIndex:857; appliedIndex:856; }","duration":"116.448084ms","start":"2026-09-22T02:03:58.868121Z","end":"2026-09-22T02:03:58.984569Z","steps":["trace[1149421531] 'read index received'  (duration: 116.22372ms)","trace[1149421531] 'applied index is now lower than readState.Index'  (duration: 223.534µs)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:03:58.98471Z","caller":"traceutil/trace.go:171","msg":"trace[1788576290] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"139.347347ms","start":"2026-09-22T02:03:58.845326Z","end":"2026-09-22T02:03:58.984674Z","steps":["trace[1788576290] 'process raft request'  (duration: 139.102372ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:03:58.984873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.69526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hhrts\" ","response":"range_response_count:1 size:3965"}
{"level":"info","ts":"2026-09-22T02:03:58.984924Z","caller":"traceutil/trace.go:171","msg":"trace[242152579] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hhrts; range_end:; response_count:1; response_revision:828; }","duration":"116.833163ms","start":"2026-09-22T02:03:58.868074Z","end":"2026-09-22T02:03:58.984907Z","steps":["trace[242152579] 'agreement among raft nodes before linearized reading'  (duration: 116.639129ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:03:59.112159Z","caller":"traceutil/trace.go:171","msg":"trace[1605678801] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"123.190919ms","start":"2026-09-22T02:03:58.988937Z","end":"2026-09-22T02:03:59.112128Z","steps":["trace[1605678801] 'process raft request'  (duration: 123.022255ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:03:59.121246Z","caller":"traceutil/trace.go:171","msg":"trace[1408690553] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"128.396802ms","start":"2026-09-22T02:03:58.992822Z","end":"2026-09-22T02:03:59.121219Z","steps":["trace[1408690553] 'process raft request'  (duration: 128.142346ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:05:23.750276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.569949ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10636553195863781474 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" mod_revision:1884 > success:<request_put:<key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" value_size:10913 >> failure:<request_range:<key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-22T02:05:23.750496Z","caller":"traceutil/trace.go:171","msg":"trace[143641114] linearizableReadLoop","detail":"{readStateIndex:2008; appliedIndex:2007; }","duration":"305.866979ms","start":"2026-09-22T02:05:23.444606Z","end":"2026-09-22T02:05:23.750473Z","steps":["trace[143641114] 'read index received'  (duration: 67.761µs)","trace[143641114] 'applied index is now lower than readState.Index'  (duration: 305.797348ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:05:23.750719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.531572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-22T02:05:23.750773Z","caller":"traceutil/trace.go:171","msg":"trace[1440946440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1933; }","duration":"182.622374ms","start":"2026-09-22T02:05:23.568135Z","end":"2026-09-22T02:05:23.750757Z","steps":["trace[1440946440] 'agreement among raft nodes before linearized reading'  (duration: 182.515872ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:05:23.750805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.164175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"}
{"level":"info","ts":"2026-09-22T02:05:23.750951Z","caller":"traceutil/trace.go:171","msg":"trace[336353411] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1933; }","duration":"306.337189ms","start":"2026-09-22T02:05:23.44456Z","end":"2026-09-22T02:05:23.750897Z","steps":["trace[336353411] 'agreement among raft nodes before linearized reading'  (duration: 306.006362ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:05:23.750948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.838502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"warn","ts":"2026-09-22T02:05:23.751015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:05:23.444542Z","time spent":"306.459361ms","remote":"127.0.0.1:58306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "}
{"level":"info","ts":"2026-09-22T02:05:23.751085Z","caller":"traceutil/trace.go:171","msg":"trace[398240050] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1933; }","duration":"229.047286ms","start":"2026-09-22T02:05:23.522017Z","end":"2026-09-22T02:05:23.751064Z","steps":["trace[398240050] 'agreement among raft nodes before linearized reading'  (duration: 228.664499ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:05:23.750844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.600588ms","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-09-22T02:05:23.751158Z","caller":"traceutil/trace.go:171","msg":"trace[1266270455] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1933; }","duration":"272.958796ms","start":"2026-09-22T02:05:23.478189Z","end":"2026-09-22T02:05:23.751147Z","steps":["trace[1266270455] 'agreement among raft nodes before linearized reading'  (duration: 272.416014ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:05:23.751162Z","caller":"traceutil/trace.go:171","msg":"trace[77096675] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"435.611042ms","start":"2026-09-22T02:05:23.315516Z","end":"2026-09-22T02:05:23.751127Z","steps":["trace[77096675] 'process raft request'  (duration: 102.396619ms)","trace[77096675] 'compare'  (duration: 331.340443ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:05:23.751272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:05:23.315491Z","time spent":"435.716194ms","remote":"127.0.0.1:38984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10970,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" mod_revision:1884 > success:<request_put:<key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" value_size:10913 >> failure:<request_range:<key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" > >"}
{"level":"info","ts":"2026-09-22T02:05:23.895658Z","caller":"traceutil/trace.go:171","msg":"trace[1604505069] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"119.601798ms","start":"2026-09-22T02:05:23.776024Z","end":"2026-09-22T02:05:23.895626Z","steps":["trace[1604505069] 'process raft request'  (duration: 82.252189ms)","trace[1604505069] 'compare'  (duration: 37.045902ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:05:26.465848Z","caller":"traceutil/trace.go:171","msg":"trace[901333883] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"103.219308ms","start":"2026-09-22T02:05:26.362598Z","end":"2026-09-22T02:05:26.465818Z","steps":["trace[901333883] 'process raft request'  (duration: 90.504245ms)","trace[901333883] 'compare'  (duration: 12.60569ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:05:40.15434Z","caller":"traceutil/trace.go:171","msg":"trace[870151280] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"169.095444ms","start":"2026-09-22T02:05:39.98518Z","end":"2026-09-22T02:05:40.154275Z","steps":["trace[870151280] 'process raft request'  (duration: 165.894376ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:06:12.909156Z","caller":"traceutil/trace.go:171","msg":"trace[716817635] transaction","detail":"{read_only:false; response_revision:2255; number_of_response:1; }","duration":"150.981637ms","start":"2026-09-22T02:06:12.758142Z","end":"2026-09-22T02:06:12.909124Z","steps":["trace[716817635] 'process raft request'  (duration: 52.961172ms)","trace[716817635] 'compare'  (duration: 97.867812ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:06:19.047145Z","caller":"traceutil/trace.go:171","msg":"trace[471994556] transaction","detail":"{read_only:false; response_revision:2286; number_of_response:1; }","duration":"119.195208ms","start":"2026-09-22T02:06:18.927924Z","end":"2026-09-22T02:06:19.04712Z","steps":["trace[471994556] 'process raft request'  (duration: 119.017015ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:06:19.059803Z","caller":"traceutil/trace.go:171","msg":"trace[1757372682] transaction","detail":"{read_only:false; response_revision:2287; number_of_response:1; }","duration":"131.385959ms","start":"2026-09-22T02:06:18.928399Z","end":"2026-09-22T02:06:19.059785Z","steps":["trace[1757372682] 'process raft request'  (duration: 131.272366ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:06:19.324805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.902229ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10636553195863782303 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:2279 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-22T02:06:19.325072Z","caller":"traceutil/trace.go:171","msg":"trace[1073565587] linearizableReadLoop","detail":"{readStateIndex:2377; appliedIndex:2375; }","duration":"158.965458ms","start":"2026-09-22T02:06:19.166047Z","end":"2026-09-22T02:06:19.325012Z","steps":["trace[1073565587] 'read index received'  (duration: 27.751523ms)","trace[1073565587] 'applied index is now lower than readState.Index'  (duration: 131.213015ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:06:19.325095Z","caller":"traceutil/trace.go:171","msg":"trace[868726903] transaction","detail":"{read_only:false; response_revision:2288; number_of_response:1; }","duration":"261.50543ms","start":"2026-09-22T02:06:19.063536Z","end":"2026-09-22T02:06:19.325042Z","steps":["trace[868726903] 'process raft request'  (duration: 130.246394ms)","trace[868726903] 'compare'  (duration: 130.641473ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:06:19.325255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.213354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"}
{"level":"info","ts":"2026-09-22T02:06:19.325247Z","caller":"traceutil/trace.go:171","msg":"trace[261016156] transaction","detail":"{read_only:false; response_revision:2289; number_of_response:1; }","duration":"261.185073ms","start":"2026-09-22T02:06:19.064037Z","end":"2026-09-22T02:06:19.325223Z","steps":["trace[261016156] 'process raft request'  (duration: 260.889066ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:06:19.325289Z","caller":"traceutil/trace.go:171","msg":"trace[1306026057] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2289; }","duration":"159.288825ms","start":"2026-09-22T02:06:19.165987Z","end":"2026-09-22T02:06:19.325276Z","steps":["trace[1306026057] 'agreement among raft nodes before linearized reading'  (duration: 159.122572ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:07:02.685986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.945613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-22T02:07:02.68617Z","caller":"traceutil/trace.go:171","msg":"trace[306697969] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2694; }","duration":"268.200689ms","start":"2026-09-22T02:07:02.417948Z","end":"2026-09-22T02:07:02.686149Z","steps":["trace[306697969] 'count revisions from in-memory index tree'  (duration: 267.833292ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:07:02.686378Z","caller":"traceutil/trace.go:171","msg":"trace[1135842584] linearizableReadLoop","detail":"{readStateIndex:2799; appliedIndex:2798; }","duration":"134.312145ms","start":"2026-09-22T02:07:02.552032Z","end":"2026-09-22T02:07:02.686344Z","steps":["trace[1135842584] 'read index received'  (duration: 131.474084ms)","trace[1135842584] 'applied index is now lower than readState.Index'  (duration: 2.83618ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:07:02.686383Z","caller":"traceutil/trace.go:171","msg":"trace[1900438417] transaction","detail":"{read_only:false; response_revision:2695; number_of_response:1; }","duration":"170.959483ms","start":"2026-09-22T02:07:02.515388Z","end":"2026-09-22T02:07:02.686348Z","steps":["trace[1900438417] 'process raft request'  (duration: 168.243044ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:07:02.686615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.569049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"info","ts":"2026-09-22T02:07:02.686686Z","caller":"traceutil/trace.go:171","msg":"trace[1156026957] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2695; }","duration":"134.681511ms","start":"2026-09-22T02:07:02.551989Z","end":"2026-09-22T02:07:02.68667Z","steps":["trace[1156026957] 'agreement among raft nodes before linearized reading'  (duration: 134.478457ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:07:36.648052Z","caller":"traceutil/trace.go:171","msg":"trace[1491405090] linearizableReadLoop","detail":"{readStateIndex:3011; appliedIndex:3010; }","duration":"128.488508ms","start":"2026-09-22T02:07:36.519529Z","end":"2026-09-22T02:07:36.648018Z","steps":["trace[1491405090] 'read index received'  (duration: 106.347347ms)","trace[1491405090] 'applied index is now lower than readState.Index'  (duration: 22.139231ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:07:36.648085Z","caller":"traceutil/trace.go:171","msg":"trace[721082957] transaction","detail":"{read_only:false; response_revision:2899; number_of_response:1; }","duration":"145.8638ms","start":"2026-09-22T02:07:36.502162Z","end":"2026-09-22T02:07:36.648026Z","steps":["trace[721082957] 'process raft request'  (duration: 123.834002ms)","trace[721082957] 'compare'  (duration: 21.761851ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:07:36.648424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.864257ms","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-09-22T02:07:36.648552Z","caller":"traceutil/trace.go:171","msg":"trace[227529977] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2899; }","duration":"129.070994ms","start":"2026-09-22T02:07:36.519455Z","end":"2026-09-22T02:07:36.648526Z","steps":["trace[227529977] 'agreement among raft nodes before linearized reading'  (duration: 128.670473ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:12:24.830026Z","caller":"traceutil/trace.go:171","msg":"trace[854479492] linearizableReadLoop","detail":"{readStateIndex:5435; appliedIndex:5434; }","duration":"232.488904ms","start":"2026-09-22T02:12:24.597513Z","end":"2026-09-22T02:12:24.830002Z","steps":["trace[854479492] 'read index received'  (duration: 197.16552ms)","trace[854479492] 'applied index is now lower than readState.Index'  (duration: 35.322364ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:12:24.830209Z","caller":"traceutil/trace.go:171","msg":"trace[387818967] transaction","detail":"{read_only:false; response_revision:5233; number_of_response:1; }","duration":"257.185397ms","start":"2026-09-22T02:12:24.572989Z","end":"2026-09-22T02:12:24.830174Z","steps":["trace[387818967] 'process raft request'  (duration: 221.69869ms)","trace[387818967] 'compare'  (duration: 35.204661ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:12:24.830398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.743819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/monitoring/loki-memcached-chunks-cache\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-22T02:12:24.830472Z","caller":"traceutil/trace.go:171","msg":"trace[1633806880] range","detail":"{range_begin:/registry/poddisruptionbudgets/monitoring/loki-memcached-chunks-cache; range_end:; response_count:0; response_revision:5233; }","duration":"232.989525ms","start":"2026-09-22T02:12:24.597468Z","end":"2026-09-22T02:12:24.830457Z","steps":["trace[1633806880] 'agreement among raft nodes before linearized reading'  (duration: 232.746249ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:12:27.96156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.499233ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10636553195863789658 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/clusterrolebindings/vector\" mod_revision:0 > success:<request_put:<key:\"/registry/clusterrolebindings/vector\" value_size:957 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-09-22T02:12:27.961694Z","caller":"traceutil/trace.go:171","msg":"trace[1368489314] linearizableReadLoop","detail":"{readStateIndex:5560; appliedIndex:5559; }","duration":"217.611202ms","start":"2026-09-22T02:12:27.744066Z","end":"2026-09-22T02:12:27.961677Z","steps":["trace[1368489314] 'read index received'  (duration: 58.863802ms)","trace[1368489314] 'applied index is now lower than readState.Index'  (duration: 158.7463ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:12:27.96173Z","caller":"traceutil/trace.go:171","msg":"trace[737627252] transaction","detail":"{read_only:false; response_revision:5354; number_of_response:1; }","duration":"276.840681ms","start":"2026-09-22T02:12:27.684857Z","end":"2026-09-22T02:12:27.961697Z","steps":["trace[737627252] 'process raft request'  (duration: 118.138653ms)","trace[737627252] 'compare'  (duration: 158.233078ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:12:27.961877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.429207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-22T02:12:27.961964Z","caller":"traceutil/trace.go:171","msg":"trace[1319955871] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:5354; }","duration":"123.554699ms","start":"2026-09-22T02:12:27.838387Z","end":"2026-09-22T02:12:27.961941Z","steps":["trace[1319955871] 'agreement among raft nodes before linearized reading'  (duration: 123.410766ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:12:27.961899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.827996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-377ccb29-3c0e-4f00-a627-12bf68e6802e\" ","response":"range_response_count:1 size:3601"}
{"level":"info","ts":"2026-09-22T02:12:27.962118Z","caller":"traceutil/trace.go:171","msg":"trace[1120449912] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-377ccb29-3c0e-4f00-a627-12bf68e6802e; range_end:; response_count:1; response_revision:5354; }","duration":"218.088332ms","start":"2026-09-22T02:12:27.744014Z","end":"2026-09-22T02:12:27.962103Z","steps":["trace[1120449912] 'agreement among raft nodes before linearized reading'  (duration: 217.745284ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:12:28.26199Z","caller":"traceutil/trace.go:171","msg":"trace[2005364529] transaction","detail":"{read_only:false; response_revision:5362; number_of_response:1; }","duration":"129.267304ms","start":"2026-09-22T02:12:28.132692Z","end":"2026-09-22T02:12:28.261959Z","steps":["trace[2005364529] 'process raft request'  (duration: 114.077395ms)","trace[2005364529] 'compare'  (duration: 14.874111ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:12:28.262456Z","caller":"traceutil/trace.go:171","msg":"trace[416791910] transaction","detail":"{read_only:false; response_revision:5363; number_of_response:1; }","duration":"128.384014ms","start":"2026-09-22T02:12:28.134053Z","end":"2026-09-22T02:12:28.262437Z","steps":["trace[416791910] 'process raft request'  (duration: 128.202419ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:12:29.789067Z","caller":"traceutil/trace.go:171","msg":"trace[1537713410] transaction","detail":"{read_only:false; response_revision:5388; number_of_response:1; }","duration":"115.515456ms","start":"2026-09-22T02:12:29.673525Z","end":"2026-09-22T02:12:29.78904Z","steps":["trace[1537713410] 'process raft request'  (duration: 88.597465ms)","trace[1537713410] 'compare'  (duration: 26.764408ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:12:52.030113Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2985}
{"level":"info","ts":"2026-09-22T02:12:52.252466Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2985,"took":"220.81464ms","hash":2906497862,"current-db-size-bytes":57204736,"current-db-size":"57 MB","current-db-size-in-use-bytes":32026624,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-09-22T02:12:52.252543Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2906497862,"revision":2985,"compact-revision":-1}
{"level":"info","ts":"2026-09-22T02:17:52.037847Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5807}
{"level":"info","ts":"2026-09-22T02:17:52.12761Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5807,"took":"86.73205ms","hash":1438523894,"current-db-size-bytes":57204736,"current-db-size":"57 MB","current-db-size-in-use-bytes":26812416,"current-db-size-in-use":"27 MB"}
{"level":"info","ts":"2026-09-22T02:17:52.12769Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1438523894,"revision":5807,"compact-revision":2985}
{"level":"warn","ts":"2026-09-22T02:19:18.077816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.498511ms","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-09-22T02:19:18.077909Z","caller":"traceutil/trace.go:171","msg":"trace[786920103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8727; }","duration":"384.676764ms","start":"2026-09-22T02:19:17.693219Z","end":"2026-09-22T02:19:18.077895Z","steps":["trace[786920103] 'range keys from in-memory index tree'  (duration: 384.217165ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:19:18.077935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:19:17.693197Z","time spent":"384.732746ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-09-22T02:19:18.077982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.868404ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10636553195863798238 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:8718 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:458 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-22T02:19:18.078124Z","caller":"traceutil/trace.go:171","msg":"trace[932875949] linearizableReadLoop","detail":"{readStateIndex:9041; appliedIndex:9040; }","duration":"252.427252ms","start":"2026-09-22T02:19:17.825673Z","end":"2026-09-22T02:19:18.0781Z","steps":["trace[932875949] 'read index received'  (duration: 145.278462ms)","trace[932875949] 'applied index is now lower than readState.Index'  (duration: 107.14683ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:19:18.078331Z","caller":"traceutil/trace.go:171","msg":"trace[148070947] transaction","detail":"{read_only:false; response_revision:8728; number_of_response:1; }","duration":"283.261248ms","start":"2026-09-22T02:19:17.795054Z","end":"2026-09-22T02:19:18.078316Z","steps":["trace[148070947] 'process raft request'  (duration: 175.924463ms)","trace[148070947] 'compare'  (duration: 106.418315ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:19:18.078543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.874222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-22T02:19:18.078575Z","caller":"traceutil/trace.go:171","msg":"trace[1196038804] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:8728; }","duration":"252.961184ms","start":"2026-09-22T02:19:17.825601Z","end":"2026-09-22T02:19:18.078562Z","steps":["trace[1196038804] 'agreement among raft nodes before linearized reading'  (duration: 252.870482ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:19:18.07892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.266914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-22T02:19:18.079004Z","caller":"traceutil/trace.go:171","msg":"trace[701176178] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:8728; }","duration":"204.406118ms","start":"2026-09-22T02:19:17.874572Z","end":"2026-09-22T02:19:18.078979Z","steps":["trace[701176178] 'agreement among raft nodes before linearized reading'  (duration: 204.251975ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:19:24.254849Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002374.wal"}
{"level":"info","ts":"2026-09-22T02:19:30.311475Z","caller":"traceutil/trace.go:171","msg":"trace[1130394850] linearizableReadLoop","detail":"{readStateIndex:9105; appliedIndex:9104; }","duration":"165.298731ms","start":"2026-09-22T02:19:30.146137Z","end":"2026-09-22T02:19:30.311436Z","steps":["trace[1130394850] 'read index received'  (duration: 165.066386ms)","trace[1130394850] 'applied index is now lower than readState.Index'  (duration: 230.405µs)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:19:30.311687Z","caller":"traceutil/trace.go:171","msg":"trace[1910856586] transaction","detail":"{read_only:false; response_revision:8790; number_of_response:1; }","duration":"179.18222ms","start":"2026-09-22T02:19:30.132479Z","end":"2026-09-22T02:19:30.311662Z","steps":["trace[1910856586] 'process raft request'  (duration: 178.655458ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:19:30.311953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.767752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-09-22T02:19:30.312085Z","caller":"traceutil/trace.go:171","msg":"trace[519236821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8790; }","duration":"165.982616ms","start":"2026-09-22T02:19:30.146065Z","end":"2026-09-22T02:19:30.312048Z","steps":["trace[519236821] 'agreement among raft nodes before linearized reading'  (duration: 165.627888ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:19:30.322564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.096113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-22T02:19:30.322649Z","caller":"traceutil/trace.go:171","msg":"trace[891516781] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8792; }","duration":"150.242476ms","start":"2026-09-22T02:19:30.172387Z","end":"2026-09-22T02:19:30.32263Z","steps":["trace[891516781] 'agreement among raft nodes before linearized reading'  (duration: 150.063931ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:19:30.928417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.909673ms","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-09-22T02:19:30.928542Z","caller":"traceutil/trace.go:171","msg":"trace[506232186] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8795; }","duration":"146.097627ms","start":"2026-09-22T02:19:30.782415Z","end":"2026-09-22T02:19:30.928513Z","steps":["trace[506232186] 'range keys from in-memory index tree'  (duration: 145.671308ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:19:33.620823Z","caller":"traceutil/trace.go:171","msg":"trace[972051732] transaction","detail":"{read_only:false; response_revision:8808; number_of_response:1; }","duration":"102.289963ms","start":"2026-09-22T02:19:33.51846Z","end":"2026-09-22T02:19:33.62075Z","steps":["trace[972051732] 'process raft request'  (duration: 102.014747ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:20:00.879133Z","caller":"traceutil/trace.go:171","msg":"trace[1473601191] transaction","detail":"{read_only:false; response_revision:9009; number_of_response:1; }","duration":"182.381298ms","start":"2026-09-22T02:20:00.696727Z","end":"2026-09-22T02:20:00.879108Z","steps":["trace[1473601191] 'process raft request'  (duration: 182.080901ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:20:01.056359Z","caller":"traceutil/trace.go:171","msg":"trace[1242035539] transaction","detail":"{read_only:false; response_revision:9010; number_of_response:1; }","duration":"174.445788ms","start":"2026-09-22T02:20:00.881882Z","end":"2026-09-22T02:20:01.056328Z","steps":["trace[1242035539] 'process raft request'  (duration: 162.556812ms)","trace[1242035539] 'compare'  (duration: 11.734222ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:20:01.058116Z","caller":"traceutil/trace.go:171","msg":"trace[1601139033] linearizableReadLoop","detail":"{readStateIndex:9335; appliedIndex:9333; }","duration":"136.734806ms","start":"2026-09-22T02:20:00.921358Z","end":"2026-09-22T02:20:01.058092Z","steps":["trace[1601139033] 'read index received'  (duration: 123.102152ms)","trace[1601139033] 'applied index is now lower than readState.Index'  (duration: 13.631404ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:20:01.058237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.855929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-22T02:20:01.058333Z","caller":"traceutil/trace.go:171","msg":"trace[1596805353] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:9010; }","duration":"136.9635ms","start":"2026-09-22T02:20:00.921354Z","end":"2026-09-22T02:20:01.058318Z","steps":["trace[1596805353] 'agreement among raft nodes before linearized reading'  (duration: 136.802007ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:20:04.691185Z","caller":"traceutil/trace.go:171","msg":"trace[278701036] transaction","detail":"{read_only:false; response_revision:9026; number_of_response:1; }","duration":"162.05971ms","start":"2026-09-22T02:20:04.529082Z","end":"2026-09-22T02:20:04.691142Z","steps":["trace[278701036] 'process raft request'  (duration: 147.899835ms)","trace[278701036] 'compare'  (duration: 14.048593ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:20:04.770815Z","caller":"traceutil/trace.go:171","msg":"trace[1043453393] transaction","detail":"{read_only:false; response_revision:9027; number_of_response:1; }","duration":"241.705716ms","start":"2026-09-22T02:20:04.529083Z","end":"2026-09-22T02:20:04.770789Z","steps":["trace[1043453393] 'process raft request'  (duration: 241.527092ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:20:04.771Z","caller":"traceutil/trace.go:171","msg":"trace[1668386733] linearizableReadLoop","detail":"{readStateIndex:9353; appliedIndex:9350; }","duration":"237.169058ms","start":"2026-09-22T02:20:04.533805Z","end":"2026-09-22T02:20:04.770974Z","steps":["trace[1668386733] 'read index received'  (duration: 143.234495ms)","trace[1668386733] 'applied index is now lower than readState.Index'  (duration: 93.932102ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:20:04.771052Z","caller":"traceutil/trace.go:171","msg":"trace[802077186] transaction","detail":"{read_only:false; response_revision:9028; number_of_response:1; }","duration":"241.289227ms","start":"2026-09-22T02:20:04.529727Z","end":"2026-09-22T02:20:04.771017Z","steps":["trace[802077186] 'process raft request'  (duration: 241.018801ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:20:04.771222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.408293ms","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-09-22T02:20:04.771253Z","caller":"traceutil/trace.go:171","msg":"trace[1543841075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9028; }","duration":"237.481526ms","start":"2026-09-22T02:20:04.533762Z","end":"2026-09-22T02:20:04.771243Z","steps":["trace[1543841075] 'agreement among raft nodes before linearized reading'  (duration: 237.311752ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:21:15.782179Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d078b1e39c21939c","local-member-applied-index":10008,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-09-22T02:21:15.794208Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10008}
{"level":"info","ts":"2026-09-22T02:21:15.79445Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5008}
{"level":"info","ts":"2026-09-22T02:21:43.633187Z","caller":"traceutil/trace.go:171","msg":"trace[18180310] transaction","detail":"{read_only:false; response_revision:9937; number_of_response:1; }","duration":"176.855589ms","start":"2026-09-22T02:21:43.456297Z","end":"2026-09-22T02:21:43.633153Z","steps":["trace[18180310] 'process raft request'  (duration: 156.827737ms)","trace[18180310] 'compare'  (duration: 19.891139ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:22:52.043948Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8046}
{"level":"info","ts":"2026-09-22T02:22:52.129449Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8046,"took":"83.401585ms","hash":2760979438,"current-db-size-bytes":57204736,"current-db-size":"57 MB","current-db-size-in-use-bytes":24887296,"current-db-size-in-use":"25 MB"}
{"level":"info","ts":"2026-09-22T02:22:52.129522Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2760979438,"revision":8046,"compact-revision":5807}
{"level":"info","ts":"2026-09-22T02:27:22.805093Z","caller":"traceutil/trace.go:171","msg":"trace[1370817733] linearizableReadLoop","detail":"{readStateIndex:12799; appliedIndex:12798; }","duration":"118.249867ms","start":"2026-09-22T02:27:22.686804Z","end":"2026-09-22T02:27:22.805054Z","steps":["trace[1370817733] 'read index received'  (duration: 94.040444ms)","trace[1370817733] 'applied index is now lower than readState.Index'  (duration: 24.208423ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:27:22.805224Z","caller":"traceutil/trace.go:171","msg":"trace[94671069] transaction","detail":"{read_only:false; response_revision:12369; number_of_response:1; }","duration":"125.873872ms","start":"2026-09-22T02:27:22.679337Z","end":"2026-09-22T02:27:22.805211Z","steps":["trace[94671069] 'process raft request'  (duration: 101.555986ms)","trace[94671069] 'compare'  (duration: 24.051381ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:27:22.805517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.690167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2n6dt\" ","response":"range_response_count:1 size:6524"}
{"level":"info","ts":"2026-09-22T02:27:22.805592Z","caller":"traceutil/trace.go:171","msg":"trace[814045036] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2n6dt; range_end:; response_count:1; response_revision:12369; }","duration":"118.81866ms","start":"2026-09-22T02:27:22.686752Z","end":"2026-09-22T02:27:22.80557Z","steps":["trace[814045036] 'agreement among raft nodes before linearized reading'  (duration: 118.554674ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:27:52.051355Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10408}
{"level":"info","ts":"2026-09-22T02:27:52.141886Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10408,"took":"88.245298ms","hash":3612170992,"current-db-size-bytes":57204736,"current-db-size":"57 MB","current-db-size-in-use-bytes":25542656,"current-db-size-in-use":"26 MB"}
{"level":"info","ts":"2026-09-22T02:27:52.141959Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3612170992,"revision":10408,"compact-revision":8046}
{"level":"info","ts":"2026-09-22T02:32:52.060422Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12573}
{"level":"info","ts":"2026-09-22T02:32:52.147018Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12573,"took":"84.27426ms","hash":470996459,"current-db-size-bytes":57204736,"current-db-size":"57 MB","current-db-size-in-use-bytes":27463680,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-09-22T02:32:52.147087Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":470996459,"revision":12573,"compact-revision":10408}
{"level":"info","ts":"2026-09-22T02:34:09.348692Z","caller":"traceutil/trace.go:171","msg":"trace[23168150] transaction","detail":"{read_only:false; response_revision:15069; number_of_response:1; }","duration":"135.686091ms","start":"2026-09-22T02:34:09.212983Z","end":"2026-09-22T02:34:09.348669Z","steps":["trace[23168150] 'process raft request'  (duration: 135.509067ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:34:10.171644Z","caller":"traceutil/trace.go:171","msg":"trace[1942751280] transaction","detail":"{read_only:false; response_revision:15074; number_of_response:1; }","duration":"310.225739ms","start":"2026-09-22T02:34:09.861388Z","end":"2026-09-22T02:34:10.171613Z","steps":["trace[1942751280] 'process raft request'  (duration: 309.987824ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:34:10.17207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:34:09.861348Z","time spent":"310.629329ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:15047 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:458 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"info","ts":"2026-09-22T02:34:10.172982Z","caller":"traceutil/trace.go:171","msg":"trace[1523790065] linearizableReadLoop","detail":"{readStateIndex:15603; appliedIndex:15601; }","duration":"172.931794ms","start":"2026-09-22T02:34:10.000027Z","end":"2026-09-22T02:34:10.172959Z","steps":["trace[1523790065] 'read index received'  (duration: 171.386431ms)","trace[1523790065] 'applied index is now lower than readState.Index'  (duration: 1.544423ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:34:10.173057Z","caller":"traceutil/trace.go:171","msg":"trace[1754011110] transaction","detail":"{read_only:false; response_revision:15076; number_of_response:1; }","duration":"151.817998ms","start":"2026-09-22T02:34:10.02122Z","end":"2026-09-22T02:34:10.173038Z","steps":["trace[1754011110] 'process raft request'  (duration: 151.699056ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:34:10.173201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.17092ms","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-09-22T02:34:10.17324Z","caller":"traceutil/trace.go:171","msg":"trace[176277422] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15076; }","duration":"173.265391ms","start":"2026-09-22T02:34:09.999964Z","end":"2026-09-22T02:34:10.173229Z","steps":["trace[176277422] 'agreement among raft nodes before linearized reading'  (duration: 173.102818ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:34:10.173028Z","caller":"traceutil/trace.go:171","msg":"trace[1210368595] transaction","detail":"{read_only:false; response_revision:15075; number_of_response:1; }","duration":"308.298318ms","start":"2026-09-22T02:34:09.86471Z","end":"2026-09-22T02:34:10.173008Z","steps":["trace[1210368595] 'process raft request'  (duration: 308.102334ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:34:10.173652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:34:09.864684Z","time spent":"308.908571ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:15048 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-09-22T02:34:20.293463Z","caller":"traceutil/trace.go:171","msg":"trace[16044300] transaction","detail":"{read_only:false; response_revision:15241; number_of_response:1; }","duration":"108.275899ms","start":"2026-09-22T02:34:20.185164Z","end":"2026-09-22T02:34:20.29344Z","steps":["trace[16044300] 'process raft request'  (duration: 107.983932ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:34:20.584998Z","caller":"traceutil/trace.go:171","msg":"trace[270874523] transaction","detail":"{read_only:false; response_revision:15243; number_of_response:1; }","duration":"372.768091ms","start":"2026-09-22T02:34:20.212201Z","end":"2026-09-22T02:34:20.584969Z","steps":["trace[270874523] 'process raft request'  (duration: 372.621418ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:34:20.584994Z","caller":"traceutil/trace.go:171","msg":"trace[1071353688] transaction","detail":"{read_only:false; response_revision:15242; number_of_response:1; }","duration":"376.990253ms","start":"2026-09-22T02:34:20.207958Z","end":"2026-09-22T02:34:20.584948Z","steps":["trace[1071353688] 'process raft request'  (duration: 340.528885ms)","trace[1071353688] 'compare'  (duration: 35.999918ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:34:20.585052Z","caller":"traceutil/trace.go:171","msg":"trace[1856504035] linearizableReadLoop","detail":"{readStateIndex:15775; appliedIndex:15772; }","duration":"360.191739ms","start":"2026-09-22T02:34:20.224834Z","end":"2026-09-22T02:34:20.585026Z","steps":["trace[1856504035] 'read index received'  (duration: 69.240685ms)","trace[1856504035] 'applied index is now lower than readState.Index'  (duration: 290.949834ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:34:20.585172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:34:20.212168Z","time spent":"372.914864ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:15131 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:464 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-22T02:34:20.585221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:34:20.207916Z","time spent":"377.182007ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:15130 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:457 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"warn","ts":"2026-09-22T02:34:20.585404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.481269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"info","ts":"2026-09-22T02:34:20.58544Z","caller":"traceutil/trace.go:171","msg":"trace[1359062828] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15244; }","duration":"356.54031ms","start":"2026-09-22T02:34:20.228887Z","end":"2026-09-22T02:34:20.585427Z","steps":["trace[1359062828] 'agreement among raft nodes before linearized reading'  (duration: 356.415317ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:34:20.585435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.180486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"warn","ts":"2026-09-22T02:34:20.585466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:34:20.228873Z","time spent":"356.58677ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-09-22T02:34:20.585491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.387656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2n6dt\" ","response":"range_response_count:1 size:6524"}
{"level":"info","ts":"2026-09-22T02:34:20.585534Z","caller":"traceutil/trace.go:171","msg":"trace[1232736223] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:15244; }","duration":"251.350909ms","start":"2026-09-22T02:34:20.334143Z","end":"2026-09-22T02:34:20.585494Z","steps":["trace[1232736223] 'agreement among raft nodes before linearized reading'  (duration: 251.217666ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:34:20.585572Z","caller":"traceutil/trace.go:171","msg":"trace[915525452] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2n6dt; range_end:; response_count:1; response_revision:15244; }","duration":"137.505109ms","start":"2026-09-22T02:34:20.448047Z","end":"2026-09-22T02:34:20.585552Z","steps":["trace[915525452] 'agreement among raft nodes before linearized reading'  (duration: 137.273794ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:34:20.585598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.440634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-09-22T02:34:20.585641Z","caller":"traceutil/trace.go:171","msg":"trace[1439815484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15244; }","duration":"360.875934ms","start":"2026-09-22T02:34:20.224753Z","end":"2026-09-22T02:34:20.585629Z","steps":["trace[1439815484] 'agreement among raft nodes before linearized reading'  (duration: 360.396954ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:34:20.585673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:34:20.22473Z","time spent":"360.932435ms","remote":"127.0.0.1:39060","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-09-22T02:34:20.585879Z","caller":"traceutil/trace.go:171","msg":"trace[503949260] transaction","detail":"{read_only:false; response_revision:15244; number_of_response:1; }","duration":"373.19706ms","start":"2026-09-22T02:34:20.212626Z","end":"2026-09-22T02:34:20.585824Z","steps":["trace[503949260] 'process raft request'  (duration: 372.306231ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:34:20.586079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:34:20.212592Z","time spent":"373.395175ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:15132 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-09-22T02:35:53.675421Z","caller":"traceutil/trace.go:171","msg":"trace[1680860984] linearizableReadLoop","detail":"{readStateIndex:16862; appliedIndex:16861; }","duration":"184.134411ms","start":"2026-09-22T02:35:53.491259Z","end":"2026-09-22T02:35:53.675393Z","steps":["trace[1680860984] 'read index received'  (duration: 183.781664ms)","trace[1680860984] 'applied index is now lower than readState.Index'  (duration: 351.607µs)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:35:53.675503Z","caller":"traceutil/trace.go:171","msg":"trace[1576154361] transaction","detail":"{read_only:false; response_revision:16299; number_of_response:1; }","duration":"184.278843ms","start":"2026-09-22T02:35:53.491194Z","end":"2026-09-22T02:35:53.675473Z","steps":["trace[1576154361] 'process raft request'  (duration: 184.005997ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:35:53.675675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.377506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-22T02:35:53.675772Z","caller":"traceutil/trace.go:171","msg":"trace[921379479] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:16299; }","duration":"184.518069ms","start":"2026-09-22T02:35:53.491228Z","end":"2026-09-22T02:35:53.675747Z","steps":["trace[921379479] 'agreement among raft nodes before linearized reading'  (duration: 184.338324ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:35:54.066263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.646573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"}
{"level":"warn","ts":"2026-09-22T02:35:54.066343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.566974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:124789"}
{"level":"info","ts":"2026-09-22T02:35:54.066389Z","caller":"traceutil/trace.go:171","msg":"trace[28219543] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:16299; }","duration":"359.831079ms","start":"2026-09-22T02:35:53.706534Z","end":"2026-09-22T02:35:54.066365Z","steps":["trace[28219543] 'range keys from in-memory index tree'  (duration: 359.477912ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:35:54.066418Z","caller":"traceutil/trace.go:171","msg":"trace[1798269154] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:16299; }","duration":"353.698887ms","start":"2026-09-22T02:35:53.712697Z","end":"2026-09-22T02:35:54.066396Z","steps":["trace[1798269154] 'range keys from in-memory index tree'  (duration: 353.269927ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:35:54.066433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:35:53.706483Z","time spent":"359.93891ms","remote":"127.0.0.1:38890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "}
{"level":"warn","ts":"2026-09-22T02:35:54.066458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:35:53.712666Z","time spent":"353.782038ms","remote":"127.0.0.1:38886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":124813,"request content":"key:\"/registry/ranges/serviceips\" "}
{"level":"info","ts":"2026-09-22T02:37:52.067808Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14579}
{"level":"info","ts":"2026-09-22T02:37:52.152161Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14579,"took":"81.709082ms","hash":1099403028,"current-db-size-bytes":57204736,"current-db-size":"57 MB","current-db-size-in-use-bytes":31576064,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-09-22T02:37:52.152247Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1099403028,"revision":14579,"compact-revision":12573}
{"level":"info","ts":"2026-09-22T02:41:38.443017Z","caller":"traceutil/trace.go:171","msg":"trace[522707666] linearizableReadLoop","detail":"{readStateIndex:19407; appliedIndex:19406; }","duration":"110.016842ms","start":"2026-09-22T02:41:38.332972Z","end":"2026-09-22T02:41:38.442989Z","steps":["trace[522707666] 'read index received'  (duration: 108.779395ms)","trace[522707666] 'applied index is now lower than readState.Index'  (duration: 1.235707ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:41:38.443384Z","caller":"traceutil/trace.go:171","msg":"trace[739223127] transaction","detail":"{read_only:false; response_revision:18763; number_of_response:1; }","duration":"111.134135ms","start":"2026-09-22T02:41:38.332236Z","end":"2026-09-22T02:41:38.44337Z","steps":["trace[739223127] 'process raft request'  (duration: 109.576192ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:41:38.443701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.718048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"}
{"level":"info","ts":"2026-09-22T02:41:38.443742Z","caller":"traceutil/trace.go:171","msg":"trace[1041594037] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18763; }","duration":"110.818889ms","start":"2026-09-22T02:41:38.332912Z","end":"2026-09-22T02:41:38.443731Z","steps":["trace[1041594037] 'agreement among raft nodes before linearized reading'  (duration: 110.653905ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:41:39.278683Z","caller":"traceutil/trace.go:171","msg":"trace[1166843586] transaction","detail":"{read_only:false; response_revision:18781; number_of_response:1; }","duration":"147.285125ms","start":"2026-09-22T02:41:39.131367Z","end":"2026-09-22T02:41:39.278652Z","steps":["trace[1166843586] 'process raft request'  (duration: 147.011269ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:41:39.278817Z","caller":"traceutil/trace.go:171","msg":"trace[849053092] linearizableReadLoop","detail":"{readStateIndex:19430; appliedIndex:19424; }","duration":"143.114776ms","start":"2026-09-22T02:41:39.135681Z","end":"2026-09-22T02:41:39.278796Z","steps":["trace[849053092] 'read index received'  (duration: 72.148346ms)","trace[849053092] 'applied index is now lower than readState.Index'  (duration: 70.96533ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:41:39.279152Z","caller":"traceutil/trace.go:171","msg":"trace[84667450] transaction","detail":"{read_only:false; response_revision:18784; number_of_response:1; }","duration":"147.607403ms","start":"2026-09-22T02:41:39.131522Z","end":"2026-09-22T02:41:39.27913Z","steps":["trace[84667450] 'process raft request'  (duration: 147.114772ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:41:39.279203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.501374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112213"}
{"level":"info","ts":"2026-09-22T02:41:39.279253Z","caller":"traceutil/trace.go:171","msg":"trace[658938616] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:18786; }","duration":"143.603497ms","start":"2026-09-22T02:41:39.135636Z","end":"2026-09-22T02:41:39.279239Z","steps":["trace[658938616] 'agreement among raft nodes before linearized reading'  (duration: 143.239909ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:41:39.279325Z","caller":"traceutil/trace.go:171","msg":"trace[29190609] transaction","detail":"{read_only:false; response_revision:18782; number_of_response:1; }","duration":"147.793876ms","start":"2026-09-22T02:41:39.131476Z","end":"2026-09-22T02:41:39.27927Z","steps":["trace[29190609] 'process raft request'  (duration: 147.083871ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:41:39.279363Z","caller":"traceutil/trace.go:171","msg":"trace[342808385] transaction","detail":"{read_only:false; response_revision:18783; number_of_response:1; }","duration":"147.844818ms","start":"2026-09-22T02:41:39.131485Z","end":"2026-09-22T02:41:39.27933Z","steps":["trace[342808385] 'process raft request'  (duration: 147.118222ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:41:39.279563Z","caller":"traceutil/trace.go:171","msg":"trace[935708910] transaction","detail":"{read_only:false; response_revision:18785; number_of_response:1; }","duration":"144.130658ms","start":"2026-09-22T02:41:39.135416Z","end":"2026-09-22T02:41:39.279546Z","steps":["trace[935708910] 'process raft request'  (duration: 143.261918ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:41:39.279725Z","caller":"traceutil/trace.go:171","msg":"trace[1860121049] transaction","detail":"{read_only:false; response_revision:18786; number_of_response:1; }","duration":"134.125312ms","start":"2026-09-22T02:41:39.145583Z","end":"2026-09-22T02:41:39.279708Z","steps":["trace[1860121049] 'process raft request'  (duration: 133.13796ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:41:39.689549Z","caller":"traceutil/trace.go:171","msg":"trace[306709634] linearizableReadLoop","detail":"{readStateIndex:19442; appliedIndex:19441; }","duration":"117.092454ms","start":"2026-09-22T02:41:39.572427Z","end":"2026-09-22T02:41:39.68952Z","steps":["trace[306709634] 'read index received'  (duration: 116.849148ms)","trace[306709634] 'applied index is now lower than readState.Index'  (duration: 241.936µs)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:41:39.689658Z","caller":"traceutil/trace.go:171","msg":"trace[1035359808] transaction","detail":"{read_only:false; response_revision:18796; number_of_response:1; }","duration":"117.617486ms","start":"2026-09-22T02:41:39.571986Z","end":"2026-09-22T02:41:39.689604Z","steps":["trace[1035359808] 'process raft request'  (duration: 117.292189ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:41:39.689786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.329719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-09-22T02:41:39.689833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.430059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:651"}
{"level":"info","ts":"2026-09-22T02:41:39.689885Z","caller":"traceutil/trace.go:171","msg":"trace[1865304795] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:18796; }","duration":"115.515531ms","start":"2026-09-22T02:41:39.574354Z","end":"2026-09-22T02:41:39.68987Z","steps":["trace[1865304795] 'agreement among raft nodes before linearized reading'  (duration: 115.345847ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:41:39.689895Z","caller":"traceutil/trace.go:171","msg":"trace[626955503] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:18796; }","duration":"117.473932ms","start":"2026-09-22T02:41:39.572391Z","end":"2026-09-22T02:41:39.689865Z","steps":["trace[626955503] 'agreement among raft nodes before linearized reading'  (duration: 117.295988ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:41:45.299798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.041759ms","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":"info","ts":"2026-09-22T02:41:45.299907Z","caller":"traceutil/trace.go:171","msg":"trace[825371262] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:18856; }","duration":"114.215103ms","start":"2026-09-22T02:41:45.185664Z","end":"2026-09-22T02:41:45.299879Z","steps":["trace[825371262] 'count revisions from in-memory index tree'  (duration: 113.874776ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:42:36.216495Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d078b1e39c21939c","local-member-applied-index":20009,"local-member-snapshot-index":10008,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-09-22T02:42:36.225155Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20009}
{"level":"info","ts":"2026-09-22T02:42:36.225282Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15009}
{"level":"info","ts":"2026-09-22T02:42:52.078229Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17039}
{"level":"info","ts":"2026-09-22T02:42:52.187138Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17039,"took":"105.540915ms","hash":897766651,"current-db-size-bytes":57204736,"current-db-size":"57 MB","current-db-size-in-use-bytes":34009088,"current-db-size-in-use":"34 MB"}
{"level":"info","ts":"2026-09-22T02:42:52.187234Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":897766651,"revision":17039,"compact-revision":14579}
{"level":"info","ts":"2026-09-22T02:46:46.940343Z","caller":"traceutil/trace.go:171","msg":"trace[414945133] transaction","detail":"{read_only:false; response_revision:21294; number_of_response:1; }","duration":"167.716736ms","start":"2026-09-22T02:46:46.772597Z","end":"2026-09-22T02:46:46.940313Z","steps":["trace[414945133] 'process raft request'  (duration: 167.268476ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:46:46.941507Z","caller":"traceutil/trace.go:171","msg":"trace[1202501759] transaction","detail":"{read_only:false; response_revision:21295; number_of_response:1; }","duration":"168.786359ms","start":"2026-09-22T02:46:46.77266Z","end":"2026-09-22T02:46:46.941447Z","steps":["trace[1202501759] 'process raft request'  (duration: 168.632556ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:47:52.084991Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19617}
{"level":"info","ts":"2026-09-22T02:47:52.210366Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19617,"took":"122.016136ms","hash":1015409317,"current-db-size-bytes":57204736,"current-db-size":"57 MB","current-db-size-in-use-bytes":30388224,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-09-22T02:47:52.210433Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1015409317,"revision":19617,"compact-revision":17039}
{"level":"warn","ts":"2026-09-22T02:47:58.261242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.395524ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10636553195863837504 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:21697 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:458 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-22T02:47:58.261638Z","caller":"traceutil/trace.go:171","msg":"trace[228115141] transaction","detail":"{read_only:false; response_revision:21707; number_of_response:1; }","duration":"269.213542ms","start":"2026-09-22T02:47:57.992388Z","end":"2026-09-22T02:47:58.261601Z","steps":["trace[228115141] 'process raft request'  (duration: 118.362978ms)","trace[228115141] 'compare'  (duration: 150.241052ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:47:58.261661Z","caller":"traceutil/trace.go:171","msg":"trace[1099706418] transaction","detail":"{read_only:false; response_revision:21708; number_of_response:1; }","duration":"262.794875ms","start":"2026-09-22T02:47:57.998832Z","end":"2026-09-22T02:47:58.261627Z","steps":["trace[1099706418] 'process raft request'  (duration: 262.675802ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:48:14.440982Z","caller":"traceutil/trace.go:171","msg":"trace[291194364] transaction","detail":"{read_only:false; response_revision:21789; number_of_response:1; }","duration":"126.762887ms","start":"2026-09-22T02:48:14.314181Z","end":"2026-09-22T02:48:14.440944Z","steps":["trace[291194364] 'process raft request'  (duration: 126.576893ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:48:14.696133Z","caller":"traceutil/trace.go:171","msg":"trace[50926390] linearizableReadLoop","detail":"{readStateIndex:22535; appliedIndex:22534; }","duration":"132.800537ms","start":"2026-09-22T02:48:14.563301Z","end":"2026-09-22T02:48:14.696101Z","steps":["trace[50926390] 'read index received'  (duration: 132.753906ms)","trace[50926390] 'applied index is now lower than readState.Index'  (duration: 44.981µs)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:48:14.696283Z","caller":"traceutil/trace.go:171","msg":"trace[2065727055] transaction","detail":"{read_only:false; response_revision:21790; number_of_response:1; }","duration":"375.549792ms","start":"2026-09-22T02:48:14.320717Z","end":"2026-09-22T02:48:14.696267Z","steps":["trace[2065727055] 'process raft request'  (duration: 374.839156ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:48:14.696553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.239497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-22T02:48:14.69663Z","caller":"traceutil/trace.go:171","msg":"trace[951580623] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:21790; }","duration":"133.36257ms","start":"2026-09-22T02:48:14.56325Z","end":"2026-09-22T02:48:14.696613Z","steps":["trace[951580623] 'agreement among raft nodes before linearized reading'  (duration: 133.242376ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:48:14.69672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:48:14.320693Z","time spent":"375.631374ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:21780 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-22T02:48:14.702169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.874579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-22T02:48:14.702221Z","caller":"traceutil/trace.go:171","msg":"trace[1503059120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21791; }","duration":"132.96478ms","start":"2026-09-22T02:48:14.569244Z","end":"2026-09-22T02:48:14.702209Z","steps":["trace[1503059120] 'agreement among raft nodes before linearized reading'  (duration: 132.858247ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:52:27.276017Z","caller":"traceutil/trace.go:171","msg":"trace[1311871089] transaction","detail":"{read_only:false; response_revision:24355; number_of_response:1; }","duration":"106.969188ms","start":"2026-09-22T02:52:27.169011Z","end":"2026-09-22T02:52:27.275981Z","steps":["trace[1311871089] 'process raft request'  (duration: 106.706782ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:52:52.092731Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21675}
{"level":"info","ts":"2026-09-22T02:52:52.216769Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21675,"took":"120.093999ms","hash":2323367064,"current-db-size-bytes":57204736,"current-db-size":"57 MB","current-db-size-in-use-bytes":42270720,"current-db-size-in-use":"42 MB"}
{"level":"info","ts":"2026-09-22T02:52:52.21684Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2323367064,"revision":21675,"compact-revision":19617}
{"level":"info","ts":"2026-09-22T02:53:35.283498Z","caller":"traceutil/trace.go:171","msg":"trace[704229854] transaction","detail":"{read_only:false; response_revision:25101; number_of_response:1; }","duration":"119.59245ms","start":"2026-09-22T02:53:35.163875Z","end":"2026-09-22T02:53:35.283468Z","steps":["trace[704229854] 'process raft request'  (duration: 42.115192ms)","trace[704229854] 'compare'  (duration: 77.051949ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:55:15.788776Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068f7.wal"}
{"level":"warn","ts":"2026-09-22T02:56:08.601808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.734391ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10636553195863850329 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" mod_revision:26528 > success:<request_put:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" value_size:507 >> failure:<request_range:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-22T02:56:08.60191Z","caller":"traceutil/trace.go:171","msg":"trace[1115581642] linearizableReadLoop","detail":"{readStateIndex:27418; appliedIndex:27417; }","duration":"287.959701ms","start":"2026-09-22T02:56:08.313935Z","end":"2026-09-22T02:56:08.601894Z","steps":["trace[1115581642] 'read index received'  (duration: 161.100437ms)","trace[1115581642] 'applied index is now lower than readState.Index'  (duration: 126.858184ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:56:08.601972Z","caller":"traceutil/trace.go:171","msg":"trace[679373686] transaction","detail":"{read_only:false; response_revision:26542; number_of_response:1; }","duration":"317.678758ms","start":"2026-09-22T02:56:08.284282Z","end":"2026-09-22T02:56:08.601961Z","steps":["trace[679373686] 'process raft request'  (duration: 190.695791ms)","trace[679373686] 'compare'  (duration: 126.265251ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:56:08.602043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:08.284257Z","time spent":"317.743408ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" mod_revision:26528 > success:<request_put:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" value_size:507 >> failure:<request_range:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" > >"}
{"level":"warn","ts":"2026-09-22T02:56:08.602166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.208966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"}
{"level":"info","ts":"2026-09-22T02:56:08.60224Z","caller":"traceutil/trace.go:171","msg":"trace[1033096533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26542; }","duration":"288.340101ms","start":"2026-09-22T02:56:08.31388Z","end":"2026-09-22T02:56:08.60222Z","steps":["trace[1033096533] 'agreement among raft nodes before linearized reading'  (duration: 288.147266ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:09.006205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.299002ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10636553195863850330 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:26531 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:458 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-22T02:56:09.006346Z","caller":"traceutil/trace.go:171","msg":"trace[91904995] transaction","detail":"{read_only:false; response_revision:26543; number_of_response:1; }","duration":"662.840333ms","start":"2026-09-22T02:56:08.343487Z","end":"2026-09-22T02:56:09.006327Z","steps":["trace[91904995] 'process raft request'  (duration: 522.285655ms)","trace[91904995] 'compare'  (duration: 140.191119ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:56:09.006405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:08.34347Z","time spent":"662.906024ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:26531 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:458 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"info","ts":"2026-09-22T02:56:09.096531Z","caller":"traceutil/trace.go:171","msg":"trace[1845108105] transaction","detail":"{read_only:false; response_revision:26544; number_of_response:1; }","duration":"597.022514ms","start":"2026-09-22T02:56:08.499479Z","end":"2026-09-22T02:56:09.096502Z","steps":["trace[1845108105] 'process raft request'  (duration: 596.790439ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:09.096738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:08.499463Z","time spent":"597.149998ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:26532 > success:<request_put:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" > >"}
{"level":"info","ts":"2026-09-22T02:56:09.096843Z","caller":"traceutil/trace.go:171","msg":"trace[1364202575] transaction","detail":"{read_only:false; response_revision:26545; number_of_response:1; }","duration":"498.974227ms","start":"2026-09-22T02:56:08.597822Z","end":"2026-09-22T02:56:09.096796Z","steps":["trace[1364202575] 'process raft request'  (duration: 498.623489ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:09.096933Z","caller":"traceutil/trace.go:171","msg":"trace[1258945632] transaction","detail":"{read_only:false; response_revision:26546; number_of_response:1; }","duration":"474.515014ms","start":"2026-09-22T02:56:08.62239Z","end":"2026-09-22T02:56:09.096905Z","steps":["trace[1258945632] 'process raft request'  (duration: 474.33589ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:09.096933Z","caller":"traceutil/trace.go:171","msg":"trace[20701499] transaction","detail":"{read_only:false; response_revision:26547; number_of_response:1; }","duration":"428.311695ms","start":"2026-09-22T02:56:08.668589Z","end":"2026-09-22T02:56:09.096901Z","steps":["trace[20701499] 'process raft request'  (duration: 428.230243ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:09.097021Z","caller":"traceutil/trace.go:171","msg":"trace[954052447] linearizableReadLoop","detail":"{readStateIndex:27421; appliedIndex:27418; }","duration":"495.076773ms","start":"2026-09-22T02:56:08.601933Z","end":"2026-09-22T02:56:09.09701Z","steps":["trace[954052447] 'read index received'  (duration: 263.856576ms)","trace[954052447] 'applied index is now lower than readState.Index'  (duration: 231.219167ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:56:09.097018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:08.597796Z","time spent":"499.12658ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:26535 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-22T02:56:09.097075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:08.622365Z","time spent":"474.671807ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:26529 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-09-22T02:56:09.097072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:08.668572Z","time spent":"428.423776ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:26537 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"info","ts":"2026-09-22T02:56:09.097136Z","caller":"traceutil/trace.go:171","msg":"trace[1537894405] transaction","detail":"{read_only:false; response_revision:26548; number_of_response:1; }","duration":"402.410461ms","start":"2026-09-22T02:56:08.694714Z","end":"2026-09-22T02:56:09.097124Z","steps":["trace[1537894405] 'process raft request'  (duration: 402.142155ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:09.097195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:08.69469Z","time spent":"402.471032ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/cert-manager/cert-manager-controller\" mod_revision:26323 > success:<request_put:<key:\"/registry/leases/cert-manager/cert-manager-controller\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/cert-manager/cert-manager-controller\" > >"}
{"level":"info","ts":"2026-09-22T02:56:09.097228Z","caller":"traceutil/trace.go:171","msg":"trace[441641328] transaction","detail":"{read_only:false; response_revision:26549; number_of_response:1; }","duration":"381.960573ms","start":"2026-09-22T02:56:08.715243Z","end":"2026-09-22T02:56:09.097204Z","steps":["trace[441641328] 'process raft request'  (duration: 381.722298ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:09.097284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:08.715219Z","time spent":"382.033564ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:26539 > success:<request_put:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" value_size:442 >> failure:<request_range:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" > >"}
{"level":"warn","ts":"2026-09-22T02:56:09.097315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.988321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11787"}
{"level":"warn","ts":"2026-09-22T02:56:09.097361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.154367ms","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-09-22T02:56:09.097398Z","caller":"traceutil/trace.go:171","msg":"trace[15097730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26549; }","duration":"403.218548ms","start":"2026-09-22T02:56:08.694171Z","end":"2026-09-22T02:56:09.09739Z","steps":["trace[15097730] 'agreement among raft nodes before linearized reading'  (duration: 403.098516ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:09.097421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.764611ms","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-09-22T02:56:09.097422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.550156ms","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":"warn","ts":"2026-09-22T02:56:09.097417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.413774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-22T02:56:09.097364Z","caller":"traceutil/trace.go:171","msg":"trace[599987761] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26549; }","duration":"481.074834ms","start":"2026-09-22T02:56:08.616276Z","end":"2026-09-22T02:56:09.09735Z","steps":["trace[599987761] 'agreement among raft nodes before linearized reading'  (duration: 480.9478ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:09.097447Z","caller":"traceutil/trace.go:171","msg":"trace[513463597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26549; }","duration":"777.815032ms","start":"2026-09-22T02:56:08.319625Z","end":"2026-09-22T02:56:09.09744Z","steps":["trace[513463597] 'agreement among raft nodes before linearized reading'  (duration: 777.75237ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:09.097462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:08.616199Z","time spent":"481.257536ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11811,"request content":"key:\"/registry/deployments/openstack/glance-api\" "}
{"level":"warn","ts":"2026-09-22T02:56:09.097469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:08.319607Z","time spent":"777.856724ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-09-22T02:56:09.097462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.358305ms","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-09-22T02:56:09.097561Z","caller":"traceutil/trace.go:171","msg":"trace[313709314] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26549; }","duration":"585.484157ms","start":"2026-09-22T02:56:08.512053Z","end":"2026-09-22T02:56:09.097537Z","steps":["trace[313709314] 'agreement among raft nodes before linearized reading'  (duration: 585.212221ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:09.097579Z","caller":"traceutil/trace.go:171","msg":"trace[605898718] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:26549; }","duration":"690.73375ms","start":"2026-09-22T02:56:08.406712Z","end":"2026-09-22T02:56:09.097445Z","steps":["trace[605898718] 'agreement among raft nodes before linearized reading'  (duration: 690.455834ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:09.097613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:08.512044Z","time spent":"585.557799ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-09-22T02:56:09.097433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:08.69416Z","time spent":"403.266868ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-09-22T02:56:09.097463Z","caller":"traceutil/trace.go:171","msg":"trace[389212942] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:26549; }","duration":"405.500876ms","start":"2026-09-22T02:56:08.691951Z","end":"2026-09-22T02:56:09.097452Z","steps":["trace[389212942] 'agreement among raft nodes before linearized reading'  (duration: 405.405625ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:09.09784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:08.691931Z","time spent":"405.892625ms","remote":"127.0.0.1:37222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true "}
{"level":"warn","ts":"2026-09-22T02:56:09.097325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.974645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"}
{"level":"info","ts":"2026-09-22T02:56:09.097971Z","caller":"traceutil/trace.go:171","msg":"trace[2012430748] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26549; }","duration":"519.687029ms","start":"2026-09-22T02:56:08.578278Z","end":"2026-09-22T02:56:09.097965Z","steps":["trace[2012430748] 'agreement among raft nodes before linearized reading'  (duration: 518.900782ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:09.097991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:08.57826Z","time spent":"519.725401ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-09-22T02:56:09.097731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:08.406696Z","time spent":"690.905614ms","remote":"127.0.0.1:41246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "}
{"level":"info","ts":"2026-09-22T02:56:09.534101Z","caller":"traceutil/trace.go:171","msg":"trace[1483126515] transaction","detail":"{read_only:false; response_revision:26554; number_of_response:1; }","duration":"172.012141ms","start":"2026-09-22T02:56:09.362035Z","end":"2026-09-22T02:56:09.534047Z","steps":["trace[1483126515] 'process raft request'  (duration: 171.827127ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:09.963877Z","caller":"traceutil/trace.go:171","msg":"trace[493109904] linearizableReadLoop","detail":"{readStateIndex:27431; appliedIndex:27430; }","duration":"294.46086ms","start":"2026-09-22T02:56:09.669391Z","end":"2026-09-22T02:56:09.963852Z","steps":["trace[493109904] 'read index received'  (duration: 294.312447ms)","trace[493109904] 'applied index is now lower than readState.Index'  (duration: 147.443µs)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:56:09.963979Z","caller":"traceutil/trace.go:171","msg":"trace[1382720397] transaction","detail":"{read_only:false; response_revision:26555; number_of_response:1; }","duration":"323.584215ms","start":"2026-09-22T02:56:09.640364Z","end":"2026-09-22T02:56:09.963948Z","steps":["trace[1382720397] 'process raft request'  (duration: 323.337099ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:09.964066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.168118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"warn","ts":"2026-09-22T02:56:09.964069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.648425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"warn","ts":"2026-09-22T02:56:09.964133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:09.64034Z","time spent":"323.710205ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:26540 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-09-22T02:56:09.964163Z","caller":"traceutil/trace.go:171","msg":"trace[774498862] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:26555; }","duration":"294.766628ms","start":"2026-09-22T02:56:09.669379Z","end":"2026-09-22T02:56:09.964145Z","steps":["trace[774498862] 'agreement among raft nodes before linearized reading'  (duration: 294.586954ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:09.964133Z","caller":"traceutil/trace.go:171","msg":"trace[1268657215] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:26555; }","duration":"175.291981ms","start":"2026-09-22T02:56:09.788827Z","end":"2026-09-22T02:56:09.964119Z","steps":["trace[1268657215] 'agreement among raft nodes before linearized reading'  (duration: 175.160109ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:15.417559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.286533ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10636553195863850423 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:26583 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:464 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-22T02:56:15.417945Z","caller":"traceutil/trace.go:171","msg":"trace[1532872820] transaction","detail":"{read_only:false; response_revision:26596; number_of_response:1; }","duration":"297.013485ms","start":"2026-09-22T02:56:15.120916Z","end":"2026-09-22T02:56:15.417929Z","steps":["trace[1532872820] 'process raft request'  (duration: 148.305753ms)","trace[1532872820] 'compare'  (duration: 147.988116ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:56:15.418124Z","caller":"traceutil/trace.go:171","msg":"trace[1656380636] linearizableReadLoop","detail":"{readStateIndex:27475; appliedIndex:27472; }","duration":"293.099732ms","start":"2026-09-22T02:56:15.125017Z","end":"2026-09-22T02:56:15.418117Z","steps":["trace[1656380636] 'read index received'  (duration: 144.220276ms)","trace[1656380636] 'applied index is now lower than readState.Index'  (duration: 148.878886ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:56:15.418175Z","caller":"traceutil/trace.go:171","msg":"trace[1153264582] transaction","detail":"{read_only:false; response_revision:26598; number_of_response:1; }","duration":"294.407259ms","start":"2026-09-22T02:56:15.123763Z","end":"2026-09-22T02:56:15.41817Z","steps":["trace[1153264582] 'process raft request'  (duration: 293.893478ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:15.41808Z","caller":"traceutil/trace.go:171","msg":"trace[1927882545] transaction","detail":"{read_only:false; response_revision:26597; number_of_response:1; }","duration":"296.451713ms","start":"2026-09-22T02:56:15.121584Z","end":"2026-09-22T02:56:15.418036Z","steps":["trace[1927882545] 'process raft request'  (duration: 296.039794ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:15.418277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.264996ms","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-09-22T02:56:15.418317Z","caller":"traceutil/trace.go:171","msg":"trace[1251288563] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26598; }","duration":"293.323747ms","start":"2026-09-22T02:56:15.124985Z","end":"2026-09-22T02:56:15.418309Z","steps":["trace[1251288563] 'agreement among raft nodes before linearized reading'  (duration: 293.228095ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:15.418391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.84119ms","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-09-22T02:56:15.41841Z","caller":"traceutil/trace.go:171","msg":"trace[402935453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26598; }","duration":"187.893151ms","start":"2026-09-22T02:56:15.230511Z","end":"2026-09-22T02:56:15.418404Z","steps":["trace[402935453] 'agreement among raft nodes before linearized reading'  (duration: 187.8481ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:15.418553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.974242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"}
{"level":"info","ts":"2026-09-22T02:56:15.418572Z","caller":"traceutil/trace.go:171","msg":"trace[352490874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26598; }","duration":"188.030973ms","start":"2026-09-22T02:56:15.230536Z","end":"2026-09-22T02:56:15.418567Z","steps":["trace[352490874] 'agreement among raft nodes before linearized reading'  (duration: 187.87084ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:15.418625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.97055ms","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-09-22T02:56:15.418691Z","caller":"traceutil/trace.go:171","msg":"trace[1140968614] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26598; }","duration":"208.072592ms","start":"2026-09-22T02:56:15.210592Z","end":"2026-09-22T02:56:15.418665Z","steps":["trace[1140968614] 'agreement among raft nodes before linearized reading'  (duration: 207.744294ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:15.41883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.190769ms","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-09-22T02:56:15.418905Z","caller":"traceutil/trace.go:171","msg":"trace[1772953569] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26598; }","duration":"204.303242ms","start":"2026-09-22T02:56:15.214581Z","end":"2026-09-22T02:56:15.418885Z","steps":["trace[1772953569] 'agreement among raft nodes before linearized reading'  (duration: 203.782861ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:15.730694Z","caller":"traceutil/trace.go:171","msg":"trace[1292174093] transaction","detail":"{read_only:false; response_revision:26599; number_of_response:1; }","duration":"306.116929ms","start":"2026-09-22T02:56:15.424555Z","end":"2026-09-22T02:56:15.730672Z","steps":["trace[1292174093] 'process raft request'  (duration: 283.895254ms)","trace[1292174093] 'compare'  (duration: 21.866667ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:56:15.730823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:15.424543Z","time spent":"306.214241ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:26588 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-09-22T02:56:15.731022Z","caller":"traceutil/trace.go:171","msg":"trace[1685916463] transaction","detail":"{read_only:false; response_revision:26600; number_of_response:1; }","duration":"306.149701ms","start":"2026-09-22T02:56:15.424848Z","end":"2026-09-22T02:56:15.730998Z","steps":["trace[1685916463] 'process raft request'  (duration: 305.785943ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:15.73118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:15.424822Z","time spent":"306.259903ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:26590 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-09-22T02:56:15.731284Z","caller":"traceutil/trace.go:171","msg":"trace[2044780893] transaction","detail":"{read_only:false; response_revision:26601; number_of_response:1; }","duration":"306.406016ms","start":"2026-09-22T02:56:15.424847Z","end":"2026-09-22T02:56:15.731253Z","steps":["trace[2044780893] 'process raft request'  (duration: 306.036699ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:15.731422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:15.424817Z","time spent":"306.533399ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:26586 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:409 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-09-22T02:56:15.731432Z","caller":"traceutil/trace.go:171","msg":"trace[1495428534] linearizableReadLoop","detail":"{readStateIndex:27480; appliedIndex:27475; }","duration":"162.408605ms","start":"2026-09-22T02:56:15.569012Z","end":"2026-09-22T02:56:15.73142Z","steps":["trace[1495428534] 'read index received'  (duration: 139.465935ms)","trace[1495428534] 'applied index is now lower than readState.Index'  (duration: 22.94186ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:56:15.731518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.520347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-22T02:56:15.731552Z","caller":"traceutil/trace.go:171","msg":"trace[1277928414] transaction","detail":"{read_only:false; response_revision:26602; number_of_response:1; }","duration":"306.5764ms","start":"2026-09-22T02:56:15.424966Z","end":"2026-09-22T02:56:15.731542Z","steps":["trace[1277928414] 'process raft request'  (duration: 306.195802ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:15.731581Z","caller":"traceutil/trace.go:171","msg":"trace[87912058] transaction","detail":"{read_only:false; response_revision:26603; number_of_response:1; }","duration":"306.60159ms","start":"2026-09-22T02:56:15.424971Z","end":"2026-09-22T02:56:15.731573Z","steps":["trace[87912058] 'process raft request'  (duration: 306.395805ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:15.731604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:15.424957Z","time spent":"306.620781ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:26587 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-09-22T02:56:15.731638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:15.424937Z","time spent":"306.670822ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:26589 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:368 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-09-22T02:56:15.731554Z","caller":"traceutil/trace.go:171","msg":"trace[1044391599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26603; }","duration":"162.594869ms","start":"2026-09-22T02:56:15.568949Z","end":"2026-09-22T02:56:15.731544Z","steps":["trace[1044391599] 'agreement among raft nodes before linearized reading'  (duration: 162.514017ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:16.296877Z","caller":"traceutil/trace.go:171","msg":"trace[1198518459] transaction","detail":"{read_only:false; response_revision:26605; number_of_response:1; }","duration":"313.718592ms","start":"2026-09-22T02:56:15.983126Z","end":"2026-09-22T02:56:16.296845Z","steps":["trace[1198518459] 'process raft request'  (duration: 313.577539ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:16.296907Z","caller":"traceutil/trace.go:171","msg":"trace[422126537] linearizableReadLoop","detail":"{readStateIndex:27483; appliedIndex:27483; }","duration":"199.751964ms","start":"2026-09-22T02:56:16.097121Z","end":"2026-09-22T02:56:16.296873Z","steps":["trace[422126537] 'read index received'  (duration: 199.735633ms)","trace[422126537] 'applied index is now lower than readState.Index'  (duration: 13.591µs)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:56:16.297048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:15.983095Z","time spent":"313.873136ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:26591 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"warn","ts":"2026-09-22T02:56:16.297203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.04206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/glance-api-c6bf8d974-s5zs8.18d78379e1f59552\" ","response":"range_response_count:1 size:2104"}
{"level":"info","ts":"2026-09-22T02:56:16.29724Z","caller":"traceutil/trace.go:171","msg":"trace[2116979627] range","detail":"{range_begin:/registry/events/openstack/glance-api-c6bf8d974-s5zs8.18d78379e1f59552; range_end:; response_count:1; response_revision:26605; }","duration":"200.155134ms","start":"2026-09-22T02:56:16.09707Z","end":"2026-09-22T02:56:16.297225Z","steps":["trace[2116979627] 'agreement among raft nodes before linearized reading'  (duration: 199.939059ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:16.375593Z","caller":"traceutil/trace.go:171","msg":"trace[1806162090] transaction","detail":"{read_only:false; response_revision:26606; number_of_response:1; }","duration":"150.139723ms","start":"2026-09-22T02:56:16.225411Z","end":"2026-09-22T02:56:16.37555Z","steps":["trace[1806162090] 'process raft request'  (duration: 101.006642ms)","trace[1806162090] 'compare'  (duration: 48.979548ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:56:16.375706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.526342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"}
{"level":"warn","ts":"2026-09-22T02:56:16.375717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.494614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-09-22T02:56:16.375777Z","caller":"traceutil/trace.go:171","msg":"trace[1033843771] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26606; }","duration":"223.688286ms","start":"2026-09-22T02:56:16.15207Z","end":"2026-09-22T02:56:16.375758Z","steps":["trace[1033843771] 'agreement among raft nodes before linearized reading'  (duration: 223.45112ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:16.375844Z","caller":"traceutil/trace.go:171","msg":"trace[1836558286] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:26606; }","duration":"147.659609ms","start":"2026-09-22T02:56:16.228155Z","end":"2026-09-22T02:56:16.375814Z","steps":["trace[1836558286] 'agreement among raft nodes before linearized reading'  (duration: 147.460365ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:16.692307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.56408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-22T02:56:16.69239Z","caller":"traceutil/trace.go:171","msg":"trace[1418793012] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26607; }","duration":"213.693862ms","start":"2026-09-22T02:56:16.478679Z","end":"2026-09-22T02:56:16.692372Z","steps":["trace[1418793012] 'range keys from in-memory index tree'  (duration: 213.418427ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:16.861365Z","caller":"traceutil/trace.go:171","msg":"trace[1940641815] transaction","detail":"{read_only:false; response_revision:26608; number_of_response:1; }","duration":"234.089059ms","start":"2026-09-22T02:56:16.62725Z","end":"2026-09-22T02:56:16.861339Z","steps":["trace[1940641815] 'process raft request'  (duration: 233.926286ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:16.902575Z","caller":"traceutil/trace.go:171","msg":"trace[278368149] linearizableReadLoop","detail":"{readStateIndex:27488; appliedIndex:27486; }","duration":"206.353625ms","start":"2026-09-22T02:56:16.696175Z","end":"2026-09-22T02:56:16.902529Z","steps":["trace[278368149] 'read index received'  (duration: 165.073861ms)","trace[278368149] 'applied index is now lower than readState.Index'  (duration: 41.278814ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:56:16.90265Z","caller":"traceutil/trace.go:171","msg":"trace[836614604] transaction","detail":"{read_only:false; response_revision:26609; number_of_response:1; }","duration":"231.305449ms","start":"2026-09-22T02:56:16.671302Z","end":"2026-09-22T02:56:16.902608Z","steps":["trace[836614604] 'process raft request'  (duration: 229.325487ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:16.902843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.655822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"}
{"level":"info","ts":"2026-09-22T02:56:16.902913Z","caller":"traceutil/trace.go:171","msg":"trace[931323807] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26609; }","duration":"206.795805ms","start":"2026-09-22T02:56:16.6961Z","end":"2026-09-22T02:56:16.902896Z","steps":["trace[931323807] 'agreement among raft nodes before linearized reading'  (duration: 206.57759ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:17.439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.641795ms","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-09-22T02:56:17.439088Z","caller":"traceutil/trace.go:171","msg":"trace[927071565] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:26611; }","duration":"172.791017ms","start":"2026-09-22T02:56:17.266277Z","end":"2026-09-22T02:56:17.439068Z","steps":["trace[927071565] 'count revisions from in-memory index tree'  (duration: 172.533612ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:17.816931Z","caller":"traceutil/trace.go:171","msg":"trace[1390268361] linearizableReadLoop","detail":"{readStateIndex:27493; appliedIndex:27491; }","duration":"248.987547ms","start":"2026-09-22T02:56:17.567917Z","end":"2026-09-22T02:56:17.816905Z","steps":["trace[1390268361] 'read index received'  (duration: 248.690251ms)","trace[1390268361] 'applied index is now lower than readState.Index'  (duration: 296.226µs)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:56:17.81696Z","caller":"traceutil/trace.go:171","msg":"trace[2141245245] transaction","detail":"{read_only:false; response_revision:26613; number_of_response:1; }","duration":"391.989777ms","start":"2026-09-22T02:56:17.424944Z","end":"2026-09-22T02:56:17.816933Z","steps":["trace[2141245245] 'process raft request'  (duration: 391.750222ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:17.817045Z","caller":"traceutil/trace.go:171","msg":"trace[1809668174] transaction","detail":"{read_only:false; response_revision:26614; number_of_response:1; }","duration":"390.051086ms","start":"2026-09-22T02:56:17.426983Z","end":"2026-09-22T02:56:17.817034Z","steps":["trace[1809668174] 'process raft request'  (duration: 389.877832ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:17.817134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:17.424918Z","time spent":"392.109099ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:26598 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-22T02:56:17.81718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:17.426973Z","time spent":"390.097277ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:26596 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-22T02:56:17.817206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.259933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-22T02:56:17.817296Z","caller":"traceutil/trace.go:171","msg":"trace[1727863769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26614; }","duration":"249.399996ms","start":"2026-09-22T02:56:17.567874Z","end":"2026-09-22T02:56:17.817274Z","steps":["trace[1727863769] 'agreement among raft nodes before linearized reading'  (duration: 249.215592ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:17.817302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.527474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"}
{"level":"info","ts":"2026-09-22T02:56:17.817361Z","caller":"traceutil/trace.go:171","msg":"trace[1770461978] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:26614; }","duration":"210.631487ms","start":"2026-09-22T02:56:17.606712Z","end":"2026-09-22T02:56:17.817344Z","steps":["trace[1770461978] 'agreement among raft nodes before linearized reading'  (duration: 210.380271ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:18.153278Z","caller":"traceutil/trace.go:171","msg":"trace[1637952459] transaction","detail":"{read_only:false; response_revision:26616; number_of_response:1; }","duration":"331.261417ms","start":"2026-09-22T02:56:17.821991Z","end":"2026-09-22T02:56:18.153253Z","steps":["trace[1637952459] 'process raft request'  (duration: 232.532656ms)","trace[1637952459] 'compare'  (duration: 98.549328ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:56:18.153413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:17.821975Z","time spent":"331.369481ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:26600 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-09-22T02:56:18.153479Z","caller":"traceutil/trace.go:171","msg":"trace[1099184014] transaction","detail":"{read_only:false; response_revision:26617; number_of_response:1; }","duration":"331.38709ms","start":"2026-09-22T02:56:17.822066Z","end":"2026-09-22T02:56:18.153454Z","steps":["trace[1099184014] 'process raft request'  (duration: 331.144645ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:18.153557Z","caller":"traceutil/trace.go:171","msg":"trace[927808713] transaction","detail":"{read_only:false; response_revision:26618; number_of_response:1; }","duration":"330.229536ms","start":"2026-09-22T02:56:17.82331Z","end":"2026-09-22T02:56:18.15354Z","steps":["trace[927808713] 'process raft request'  (duration: 330.105814ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:18.153611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:17.822057Z","time spent":"331.479713ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:26601 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-09-22T02:56:18.15362Z","caller":"traceutil/trace.go:171","msg":"trace[1732485388] linearizableReadLoop","detail":"{readStateIndex:27498; appliedIndex:27494; }","duration":"150.985741ms","start":"2026-09-22T02:56:18.002613Z","end":"2026-09-22T02:56:18.153599Z","steps":["trace[1732485388] 'read index received'  (duration: 51.85736ms)","trace[1732485388] 'applied index is now lower than readState.Index'  (duration: 99.124741ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:56:18.153673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:17.82327Z","time spent":"330.351468ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:26602 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-09-22T02:56:18.153736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.110713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-22T02:56:18.153786Z","caller":"traceutil/trace.go:171","msg":"trace[1175637192] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:26619; }","duration":"151.203476ms","start":"2026-09-22T02:56:18.002566Z","end":"2026-09-22T02:56:18.153769Z","steps":["trace[1175637192] 'agreement among raft nodes before linearized reading'  (duration: 151.099624ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:18.153987Z","caller":"traceutil/trace.go:171","msg":"trace[762224912] transaction","detail":"{read_only:false; response_revision:26619; number_of_response:1; }","duration":"330.350378ms","start":"2026-09-22T02:56:17.823605Z","end":"2026-09-22T02:56:18.153955Z","steps":["trace[762224912] 'process raft request'  (duration: 329.904049ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:18.154263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:17.823592Z","time spent":"330.492772ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:26603 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-09-22T02:56:18.514058Z","caller":"traceutil/trace.go:171","msg":"trace[48408273] transaction","detail":"{read_only:false; response_revision:26620; number_of_response:1; }","duration":"209.581923ms","start":"2026-09-22T02:56:18.304435Z","end":"2026-09-22T02:56:18.514017Z","steps":["trace[48408273] 'process raft request'  (duration: 196.929043ms)","trace[48408273] 'compare'  (duration: 12.248562ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:56:18.517709Z","caller":"traceutil/trace.go:171","msg":"trace[1881282229] transaction","detail":"{read_only:false; response_revision:26621; number_of_response:1; }","duration":"136.179204ms","start":"2026-09-22T02:56:18.381509Z","end":"2026-09-22T02:56:18.517688Z","steps":["trace[1881282229] 'process raft request'  (duration: 136.070011ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:19.223923Z","caller":"traceutil/trace.go:171","msg":"trace[180317631] transaction","detail":"{read_only:false; response_revision:26623; number_of_response:1; }","duration":"130.703986ms","start":"2026-09-22T02:56:19.093195Z","end":"2026-09-22T02:56:19.223899Z","steps":["trace[180317631] 'process raft request'  (duration: 130.462121ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:19.261628Z","caller":"traceutil/trace.go:171","msg":"trace[1165264160] linearizableReadLoop","detail":"{readStateIndex:27503; appliedIndex:27501; }","duration":"140.964366ms","start":"2026-09-22T02:56:19.120639Z","end":"2026-09-22T02:56:19.261603Z","steps":["trace[1165264160] 'read index received'  (duration: 103.011694ms)","trace[1165264160] 'applied index is now lower than readState.Index'  (duration: 37.951652ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:56:19.261698Z","caller":"traceutil/trace.go:171","msg":"trace[1688594922] transaction","detail":"{read_only:false; response_revision:26624; number_of_response:1; }","duration":"168.354622ms","start":"2026-09-22T02:56:19.09332Z","end":"2026-09-22T02:56:19.261674Z","steps":["trace[1688594922] 'process raft request'  (duration: 168.106106ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:19.26177Z","caller":"traceutil/trace.go:171","msg":"trace[1074025298] transaction","detail":"{read_only:false; response_revision:26625; number_of_response:1; }","duration":"118.908864ms","start":"2026-09-22T02:56:19.142809Z","end":"2026-09-22T02:56:19.261717Z","steps":["trace[1074025298] 'process raft request'  (duration: 118.752191ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:19.262158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.517167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:277881"}
{"level":"info","ts":"2026-09-22T02:56:19.262204Z","caller":"traceutil/trace.go:171","msg":"trace[22192973] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:26625; }","duration":"141.60265ms","start":"2026-09-22T02:56:19.120589Z","end":"2026-09-22T02:56:19.262192Z","steps":["trace[22192973] 'agreement among raft nodes before linearized reading'  (duration: 141.101739ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:19.884388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.258025ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10636553195863850498 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:26554 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:600 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-22T02:56:19.884589Z","caller":"traceutil/trace.go:171","msg":"trace[176127567] linearizableReadLoop","detail":"{readStateIndex:27506; appliedIndex:27505; }","duration":"149.787774ms","start":"2026-09-22T02:56:19.734778Z","end":"2026-09-22T02:56:19.884566Z","steps":["trace[176127567] 'read index received'  (duration: 28.212143ms)","trace[176127567] 'applied index is now lower than readState.Index'  (duration: 121.574021ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:56:19.8847Z","caller":"traceutil/trace.go:171","msg":"trace[600502733] transaction","detail":"{read_only:false; response_revision:26627; number_of_response:1; }","duration":"207.216074ms","start":"2026-09-22T02:56:19.677472Z","end":"2026-09-22T02:56:19.884688Z","steps":["trace[600502733] 'process raft request'  (duration: 85.581801ms)","trace[600502733] 'compare'  (duration: 121.147222ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:56:19.884869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.071939ms","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-09-22T02:56:19.884913Z","caller":"traceutil/trace.go:171","msg":"trace[413624188] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:26628; }","duration":"150.160182ms","start":"2026-09-22T02:56:19.734738Z","end":"2026-09-22T02:56:19.884898Z","steps":["trace[413624188] 'agreement among raft nodes before linearized reading'  (duration: 150.066871ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:20.131476Z","caller":"traceutil/trace.go:171","msg":"trace[2029585182] linearizableReadLoop","detail":"{readStateIndex:27508; appliedIndex:27507; }","duration":"246.794961ms","start":"2026-09-22T02:56:19.884652Z","end":"2026-09-22T02:56:20.131447Z","steps":["trace[2029585182] 'read index received'  (duration: 174.510603ms)","trace[2029585182] 'applied index is now lower than readState.Index'  (duration: 72.283158ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:56:20.131703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.374161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"}
{"level":"info","ts":"2026-09-22T02:56:20.131755Z","caller":"traceutil/trace.go:171","msg":"trace[1430320565] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26629; }","duration":"288.471321ms","start":"2026-09-22T02:56:19.843269Z","end":"2026-09-22T02:56:20.13174Z","steps":["trace[1430320565] 'agreement among raft nodes before linearized reading'  (duration: 288.289108ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:20.131894Z","caller":"traceutil/trace.go:171","msg":"trace[864564631] transaction","detail":"{read_only:false; response_revision:26629; number_of_response:1; }","duration":"308.444869ms","start":"2026-09-22T02:56:19.823409Z","end":"2026-09-22T02:56:20.131854Z","steps":["trace[864564631] 'process raft request'  (duration: 235.753293ms)","trace[864564631] 'compare'  (duration: 72.146014ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:56:20.13208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:19.823382Z","time spent":"308.619913ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:26614 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"info","ts":"2026-09-22T02:56:20.348971Z","caller":"traceutil/trace.go:171","msg":"trace[102573573] transaction","detail":"{read_only:false; response_revision:26632; number_of_response:1; }","duration":"171.322096ms","start":"2026-09-22T02:56:20.177624Z","end":"2026-09-22T02:56:20.348946Z","steps":["trace[102573573] 'process raft request'  (duration: 147.354032ms)","trace[102573573] 'compare'  (duration: 23.81286ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:56:20.349223Z","caller":"traceutil/trace.go:171","msg":"trace[400098270] transaction","detail":"{read_only:false; response_revision:26634; number_of_response:1; }","duration":"170.840754ms","start":"2026-09-22T02:56:20.178359Z","end":"2026-09-22T02:56:20.3492Z","steps":["trace[400098270] 'process raft request'  (duration: 170.777964ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:20.349364Z","caller":"traceutil/trace.go:171","msg":"trace[1885396412] transaction","detail":"{read_only:false; response_revision:26633; number_of_response:1; }","duration":"171.50608ms","start":"2026-09-22T02:56:20.17783Z","end":"2026-09-22T02:56:20.349336Z","steps":["trace[1885396412] 'process raft request'  (duration: 171.084401ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:20.893264Z","caller":"traceutil/trace.go:171","msg":"trace[421515562] transaction","detail":"{read_only:false; response_revision:26635; number_of_response:1; }","duration":"374.548464ms","start":"2026-09-22T02:56:20.518688Z","end":"2026-09-22T02:56:20.893237Z","steps":["trace[421515562] 'process raft request'  (duration: 374.371611ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:20.893427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:20.518661Z","time spent":"374.690997ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:26620 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-09-22T02:56:20.913979Z","caller":"traceutil/trace.go:171","msg":"trace[1384384894] linearizableReadLoop","detail":"{readStateIndex:27515; appliedIndex:27513; }","duration":"345.473861ms","start":"2026-09-22T02:56:20.56848Z","end":"2026-09-22T02:56:20.913954Z","steps":["trace[1384384894] 'read index received'  (duration: 324.458932ms)","trace[1384384894] 'applied index is now lower than readState.Index'  (duration: 21.013839ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:56:20.914078Z","caller":"traceutil/trace.go:171","msg":"trace[1745211010] transaction","detail":"{read_only:false; response_revision:26636; number_of_response:1; }","duration":"390.745211ms","start":"2026-09-22T02:56:20.523322Z","end":"2026-09-22T02:56:20.914068Z","steps":["trace[1745211010] 'process raft request'  (duration: 390.492876ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:20.914238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:20.523309Z","time spent":"390.793181ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/capi-system/controller-leader-election-capi\" mod_revision:26621 > success:<request_put:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" > >"}
{"level":"warn","ts":"2026-09-22T02:56:20.91438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.877389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-22T02:56:20.914493Z","caller":"traceutil/trace.go:171","msg":"trace[203866875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26636; }","duration":"346.018373ms","start":"2026-09-22T02:56:20.568431Z","end":"2026-09-22T02:56:20.914449Z","steps":["trace[203866875] 'agreement among raft nodes before linearized reading'  (duration: 345.812049ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:20.914545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:20.568411Z","time spent":"346.122376ms","remote":"127.0.0.1:38816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-09-22T02:56:26.526678Z","caller":"traceutil/trace.go:171","msg":"trace[2104498751] linearizableReadLoop","detail":"{readStateIndex:27557; appliedIndex:27556; }","duration":"146.156817ms","start":"2026-09-22T02:56:26.380497Z","end":"2026-09-22T02:56:26.526654Z","steps":["trace[2104498751] 'read index received'  (duration: 145.993074ms)","trace[2104498751] 'applied index is now lower than readState.Index'  (duration: 162.593µs)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:56:26.526795Z","caller":"traceutil/trace.go:171","msg":"trace[270242688] transaction","detail":"{read_only:false; response_revision:26676; number_of_response:1; }","duration":"271.192463ms","start":"2026-09-22T02:56:26.255573Z","end":"2026-09-22T02:56:26.526765Z","steps":["trace[270242688] 'process raft request'  (duration: 270.923217ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:26.526896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.388831ms","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-09-22T02:56:26.52693Z","caller":"traceutil/trace.go:171","msg":"trace[1663560297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26676; }","duration":"146.477844ms","start":"2026-09-22T02:56:26.380442Z","end":"2026-09-22T02:56:26.52692Z","steps":["trace[1663560297] 'agreement among raft nodes before linearized reading'  (duration: 146.339771ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:26.52698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.862381ms","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-09-22T02:56:26.527028Z","caller":"traceutil/trace.go:171","msg":"trace[245870565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26676; }","duration":"145.958263ms","start":"2026-09-22T02:56:26.381057Z","end":"2026-09-22T02:56:26.527015Z","steps":["trace[245870565] 'agreement among raft nodes before linearized reading'  (duration: 145.797969ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:26.527038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.104686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"}
{"level":"info","ts":"2026-09-22T02:56:26.527126Z","caller":"traceutil/trace.go:171","msg":"trace[14719506] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26676; }","duration":"146.224928ms","start":"2026-09-22T02:56:26.380875Z","end":"2026-09-22T02:56:26.5271Z","steps":["trace[14719506] 'agreement among raft nodes before linearized reading'  (duration: 145.961152ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:26.844189Z","caller":"traceutil/trace.go:171","msg":"trace[286681937] transaction","detail":"{read_only:false; response_revision:26677; number_of_response:1; }","duration":"312.753471ms","start":"2026-09-22T02:56:26.531409Z","end":"2026-09-22T02:56:26.844162Z","steps":["trace[286681937] 'process raft request'  (duration: 312.560067ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:26.844331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:26.531387Z","time spent":"312.872713ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:26664 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-09-22T02:56:26.97743Z","caller":"traceutil/trace.go:171","msg":"trace[2002428135] transaction","detail":"{read_only:false; response_revision:26679; number_of_response:1; }","duration":"445.188835ms","start":"2026-09-22T02:56:26.532222Z","end":"2026-09-22T02:56:26.97741Z","steps":["trace[2002428135] 'process raft request'  (duration: 445.112354ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:26.97743Z","caller":"traceutil/trace.go:171","msg":"trace[924301297] transaction","detail":"{read_only:false; response_revision:26678; number_of_response:1; }","duration":"445.529442ms","start":"2026-09-22T02:56:26.531878Z","end":"2026-09-22T02:56:26.977407Z","steps":["trace[924301297] 'process raft request'  (duration: 442.534298ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:26.977575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:26.531856Z","time spent":"445.643114ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:26663 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-09-22T02:56:26.977586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:26.532211Z","time spent":"445.281647ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:26662 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-09-22T02:56:26.977772Z","caller":"traceutil/trace.go:171","msg":"trace[729507542] transaction","detail":"{read_only:false; response_revision:26680; number_of_response:1; }","duration":"247.850483ms","start":"2026-09-22T02:56:26.729911Z","end":"2026-09-22T02:56:26.977762Z","steps":["trace[729507542] 'process raft request'  (duration: 247.463565ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:27.398764Z","caller":"traceutil/trace.go:171","msg":"trace[428952356] transaction","detail":"{read_only:false; response_revision:26684; number_of_response:1; }","duration":"461.999354ms","start":"2026-09-22T02:56:26.936738Z","end":"2026-09-22T02:56:27.398738Z","steps":["trace[428952356] 'process raft request'  (duration: 461.957953ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:27.398903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:26.936722Z","time spent":"462.112977ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/capi-system/controller-leader-election-capi\" mod_revision:26667 > success:<request_put:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" > >"}
{"level":"info","ts":"2026-09-22T02:56:27.399065Z","caller":"traceutil/trace.go:171","msg":"trace[684141867] transaction","detail":"{read_only:false; response_revision:26681; number_of_response:1; }","duration":"484.092057ms","start":"2026-09-22T02:56:26.914941Z","end":"2026-09-22T02:56:27.399033Z","steps":["trace[684141867] 'process raft request'  (duration: 483.519185ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:27.399125Z","caller":"traceutil/trace.go:171","msg":"trace[1121618298] transaction","detail":"{read_only:false; response_revision:26682; number_of_response:1; }","duration":"481.439491ms","start":"2026-09-22T02:56:26.917674Z","end":"2026-09-22T02:56:27.399114Z","steps":["trace[1121618298] 'process raft request'  (duration: 480.93942ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:27.399226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:26.917646Z","time spent":"481.541333ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:26665 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"warn","ts":"2026-09-22T02:56:27.399243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:26.914908Z","time spent":"484.247711ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/capi-kubeadm-control-plane-system/kubeadm-control-plane-manager-leader-election-capi\" mod_revision:26643 > success:<request_put:<key:\"/registry/leases/capi-kubeadm-control-plane-system/kubeadm-control-plane-manager-leader-election-capi\" value_size:519 >> failure:<request_range:<key:\"/registry/leases/capi-kubeadm-control-plane-system/kubeadm-control-plane-manager-leader-election-capi\" > >"}
{"level":"info","ts":"2026-09-22T02:56:27.399367Z","caller":"traceutil/trace.go:171","msg":"trace[434815079] transaction","detail":"{read_only:false; response_revision:26683; number_of_response:1; }","duration":"463.062007ms","start":"2026-09-22T02:56:26.936295Z","end":"2026-09-22T02:56:27.399357Z","steps":["trace[434815079] 'process raft request'  (duration: 462.367693ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:27.399426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:26.93628Z","time spent":"463.115388ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" mod_revision:26666 > success:<request_put:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" value_size:507 >> failure:<request_range:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" > >"}
{"level":"info","ts":"2026-09-22T02:56:27.399561Z","caller":"traceutil/trace.go:171","msg":"trace[803972327] linearizableReadLoop","detail":"{readStateIndex:27565; appliedIndex:27565; }","duration":"346.122235ms","start":"2026-09-22T02:56:27.05341Z","end":"2026-09-22T02:56:27.399532Z","steps":["trace[803972327] 'read index received'  (duration: 346.111015ms)","trace[803972327] 'applied index is now lower than readState.Index'  (duration: 8.9µs)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:56:27.399832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.407451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"}
{"level":"info","ts":"2026-09-22T02:56:27.399939Z","caller":"traceutil/trace.go:171","msg":"trace[1127864033] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26684; }","duration":"346.549405ms","start":"2026-09-22T02:56:27.053367Z","end":"2026-09-22T02:56:27.399916Z","steps":["trace[1127864033] 'agreement among raft nodes before linearized reading'  (duration: 346.264589ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:27.400022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:56:27.053348Z","time spent":"346.659677ms","remote":"127.0.0.1:38984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12241,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "}
{"level":"info","ts":"2026-09-22T02:56:27.413319Z","caller":"traceutil/trace.go:171","msg":"trace[1112565633] transaction","detail":"{read_only:false; response_revision:26686; number_of_response:1; }","duration":"104.347942ms","start":"2026-09-22T02:56:27.308922Z","end":"2026-09-22T02:56:27.41327Z","steps":["trace[1112565633] 'process raft request'  (duration: 103.679168ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:27.413343Z","caller":"traceutil/trace.go:171","msg":"trace[1890301483] transaction","detail":"{read_only:false; response_revision:26685; number_of_response:1; }","duration":"104.463535ms","start":"2026-09-22T02:56:27.308841Z","end":"2026-09-22T02:56:27.413305Z","steps":["trace[1890301483] 'process raft request'  (duration: 103.601997ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:27.710197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.10961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-22T02:56:27.71028Z","caller":"traceutil/trace.go:171","msg":"trace[544293991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26686; }","duration":"141.245752ms","start":"2026-09-22T02:56:27.569016Z","end":"2026-09-22T02:56:27.710262Z","steps":["trace[544293991] 'range keys from in-memory index tree'  (duration: 141.021987ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:27.710356Z","caller":"traceutil/trace.go:171","msg":"trace[1757854172] transaction","detail":"{read_only:false; response_revision:26687; number_of_response:1; }","duration":"103.765331ms","start":"2026-09-22T02:56:27.606519Z","end":"2026-09-22T02:56:27.710284Z","steps":["trace[1757854172] 'process raft request'  (duration: 101.833359ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:27.710959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.187187ms","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-09-22T02:56:27.711055Z","caller":"traceutil/trace.go:171","msg":"trace[1138499197] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26686; }","duration":"138.34534ms","start":"2026-09-22T02:56:27.572689Z","end":"2026-09-22T02:56:27.711035Z","steps":["trace[1138499197] 'range keys from in-memory index tree'  (duration: 137.228587ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:28.114337Z","caller":"traceutil/trace.go:171","msg":"trace[1636342085] linearizableReadLoop","detail":"{readStateIndex:27569; appliedIndex:27568; }","duration":"174.607775ms","start":"2026-09-22T02:56:27.939665Z","end":"2026-09-22T02:56:28.114273Z","steps":["trace[1636342085] 'read index received'  (duration: 174.35375ms)","trace[1636342085] 'applied index is now lower than readState.Index'  (duration: 252.925µs)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:56:28.114515Z","caller":"traceutil/trace.go:171","msg":"trace[1433900083] transaction","detail":"{read_only:false; response_revision:26688; number_of_response:1; }","duration":"187.085702ms","start":"2026-09-22T02:56:27.927389Z","end":"2026-09-22T02:56:28.114474Z","steps":["trace[1433900083] 'process raft request'  (duration: 186.709575ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:28.114532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.82868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-22T02:56:28.114588Z","caller":"traceutil/trace.go:171","msg":"trace[95193837] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:26688; }","duration":"174.947233ms","start":"2026-09-22T02:56:27.939625Z","end":"2026-09-22T02:56:28.114572Z","steps":["trace[95193837] 'agreement among raft nodes before linearized reading'  (duration: 174.79677ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:33.660909Z","caller":"traceutil/trace.go:171","msg":"trace[2005191872] transaction","detail":"{read_only:false; response_revision:26782; number_of_response:1; }","duration":"238.411421ms","start":"2026-09-22T02:56:33.422446Z","end":"2026-09-22T02:56:33.660858Z","steps":["trace[2005191872] 'process raft request'  (duration: 204.415263ms)","trace[2005191872] 'compare'  (duration: 33.587149ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:56:33.661222Z","caller":"traceutil/trace.go:171","msg":"trace[1516902422] transaction","detail":"{read_only:false; response_revision:26783; number_of_response:1; }","duration":"237.259566ms","start":"2026-09-22T02:56:33.423933Z","end":"2026-09-22T02:56:33.661192Z","steps":["trace[1516902422] 'process raft request'  (duration: 236.840397ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:33.661386Z","caller":"traceutil/trace.go:171","msg":"trace[199506136] transaction","detail":"{read_only:false; response_revision:26784; number_of_response:1; }","duration":"231.045053ms","start":"2026-09-22T02:56:33.430309Z","end":"2026-09-22T02:56:33.661354Z","steps":["trace[199506136] 'process raft request'  (duration: 230.800228ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:33.661603Z","caller":"traceutil/trace.go:171","msg":"trace[44584855] transaction","detail":"{read_only:false; response_revision:26785; number_of_response:1; }","duration":"229.781527ms","start":"2026-09-22T02:56:33.431809Z","end":"2026-09-22T02:56:33.66159Z","steps":["trace[44584855] 'process raft request'  (duration: 229.47332ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:34.074279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.887316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bc672a59-0094-434b-99aa-8a7d52f0317b\" ","response":"range_response_count:1 size:3644"}
{"level":"info","ts":"2026-09-22T02:56:34.074457Z","caller":"traceutil/trace.go:171","msg":"trace[1994496784] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bc672a59-0094-434b-99aa-8a7d52f0317b; range_end:; response_count:1; response_revision:26786; }","duration":"142.121491ms","start":"2026-09-22T02:56:33.932315Z","end":"2026-09-22T02:56:34.074436Z","steps":["trace[1994496784] 'range keys from in-memory index tree'  (duration: 141.671281ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:34.214076Z","caller":"traceutil/trace.go:171","msg":"trace[1094788569] transaction","detail":"{read_only:false; response_revision:26787; number_of_response:1; }","duration":"130.572473ms","start":"2026-09-22T02:56:34.083475Z","end":"2026-09-22T02:56:34.214047Z","steps":["trace[1094788569] 'process raft request'  (duration: 130.345409ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:34.241188Z","caller":"traceutil/trace.go:171","msg":"trace[1265080805] transaction","detail":"{read_only:false; response_revision:26788; number_of_response:1; }","duration":"107.350927ms","start":"2026-09-22T02:56:34.133775Z","end":"2026-09-22T02:56:34.241126Z","steps":["trace[1265080805] 'process raft request'  (duration: 107.02685ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:41.321862Z","caller":"traceutil/trace.go:171","msg":"trace[1815777729] transaction","detail":"{read_only:false; response_revision:26862; number_of_response:1; }","duration":"212.978188ms","start":"2026-09-22T02:56:41.108843Z","end":"2026-09-22T02:56:41.321821Z","steps":["trace[1815777729] 'process raft request'  (duration: 187.563534ms)","trace[1815777729] 'compare'  (duration: 24.996374ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:56:41.461868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.250061ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10636553195863851048 > lease_revoke:<id:139ca0c6da02be5e>","response":"size:30"}
{"level":"info","ts":"2026-09-22T02:56:44.043492Z","caller":"traceutil/trace.go:171","msg":"trace[276133035] transaction","detail":"{read_only:false; response_revision:26889; number_of_response:1; }","duration":"180.412201ms","start":"2026-09-22T02:56:43.863047Z","end":"2026-09-22T02:56:44.043459Z","steps":["trace[276133035] 'process raft request'  (duration: 180.235437ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:46.535861Z","caller":"traceutil/trace.go:171","msg":"trace[1327291564] transaction","detail":"{read_only:false; response_revision:26904; number_of_response:1; }","duration":"167.8206ms","start":"2026-09-22T02:56:46.36801Z","end":"2026-09-22T02:56:46.535831Z","steps":["trace[1327291564] 'process raft request'  (duration: 167.669557ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:46.566697Z","caller":"traceutil/trace.go:171","msg":"trace[1256663090] linearizableReadLoop","detail":"{readStateIndex:27791; appliedIndex:27789; }","duration":"181.516474ms","start":"2026-09-22T02:56:46.385156Z","end":"2026-09-22T02:56:46.566672Z","steps":["trace[1256663090] 'read index received'  (duration: 150.546852ms)","trace[1256663090] 'applied index is now lower than readState.Index'  (duration: 30.968752ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:56:46.566754Z","caller":"traceutil/trace.go:171","msg":"trace[1728183056] transaction","detail":"{read_only:false; response_revision:26905; number_of_response:1; }","duration":"196.723309ms","start":"2026-09-22T02:56:46.370003Z","end":"2026-09-22T02:56:46.566726Z","steps":["trace[1728183056] 'process raft request'  (duration: 196.542096ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:46.566897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.718728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"}
{"level":"info","ts":"2026-09-22T02:56:46.566934Z","caller":"traceutil/trace.go:171","msg":"trace[965789261] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26905; }","duration":"181.799621ms","start":"2026-09-22T02:56:46.385123Z","end":"2026-09-22T02:56:46.566923Z","steps":["trace[965789261] 'agreement among raft nodes before linearized reading'  (duration: 181.654258ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:52.010439Z","caller":"traceutil/trace.go:171","msg":"trace[1140128229] transaction","detail":"{read_only:false; response_revision:26948; number_of_response:1; }","duration":"123.248816ms","start":"2026-09-22T02:56:51.887149Z","end":"2026-09-22T02:56:52.010398Z","steps":["trace[1140128229] 'process raft request'  (duration: 114.506129ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:56.770085Z","caller":"traceutil/trace.go:171","msg":"trace[275110524] linearizableReadLoop","detail":"{readStateIndex:27870; appliedIndex:27868; }","duration":"130.845479ms","start":"2026-09-22T02:56:56.639214Z","end":"2026-09-22T02:56:56.77006Z","steps":["trace[275110524] 'read index received'  (duration: 39.309702ms)","trace[275110524] 'applied index is now lower than readState.Index'  (duration: 91.534607ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:56:56.770319Z","caller":"traceutil/trace.go:171","msg":"trace[688479662] transaction","detail":"{read_only:false; response_revision:26982; number_of_response:1; }","duration":"139.448235ms","start":"2026-09-22T02:56:56.630813Z","end":"2026-09-22T02:56:56.770261Z","steps":["trace[688479662] 'process raft request'  (duration: 138.967644ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:56.770523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.324152ms","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-09-22T02:56:56.770596Z","caller":"traceutil/trace.go:171","msg":"trace[305960784] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26982; }","duration":"104.457555ms","start":"2026-09-22T02:56:56.66612Z","end":"2026-09-22T02:56:56.770578Z","steps":["trace[305960784] 'agreement among raft nodes before linearized reading'  (duration: 104.203959ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:56.770635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.279039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"}
{"level":"info","ts":"2026-09-22T02:56:56.770916Z","caller":"traceutil/trace.go:171","msg":"trace[738376181] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26982; }","duration":"131.510714ms","start":"2026-09-22T02:56:56.639162Z","end":"2026-09-22T02:56:56.770672Z","steps":["trace[738376181] 'agreement among raft nodes before linearized reading'  (duration: 131.095285ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:58.896109Z","caller":"traceutil/trace.go:171","msg":"trace[607448394] linearizableReadLoop","detail":"{readStateIndex:27891; appliedIndex:27889; }","duration":"113.080439ms","start":"2026-09-22T02:56:58.78301Z","end":"2026-09-22T02:56:58.896091Z","steps":["trace[607448394] 'read index received'  (duration: 53.077035ms)","trace[607448394] 'applied index is now lower than readState.Index'  (duration: 60.002654ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:56:58.896252Z","caller":"traceutil/trace.go:171","msg":"trace[1807576689] transaction","detail":"{read_only:false; response_revision:27003; number_of_response:1; }","duration":"119.967846ms","start":"2026-09-22T02:56:58.776256Z","end":"2026-09-22T02:56:58.896223Z","steps":["trace[1807576689] 'process raft request'  (duration: 119.777312ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:58.896368Z","caller":"traceutil/trace.go:171","msg":"trace[1937169627] transaction","detail":"{read_only:false; response_revision:27002; number_of_response:1; }","duration":"124.758119ms","start":"2026-09-22T02:56:58.771578Z","end":"2026-09-22T02:56:58.896336Z","steps":["trace[1937169627] 'process raft request'  (duration: 64.469828ms)","trace[1937169627] 'compare'  (duration: 59.862192ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:56:58.896478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.455057ms","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-09-22T02:56:58.896517Z","caller":"traceutil/trace.go:171","msg":"trace[380737920] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27003; }","duration":"113.548219ms","start":"2026-09-22T02:56:58.782959Z","end":"2026-09-22T02:56:58.896507Z","steps":["trace[380737920] 'agreement among raft nodes before linearized reading'  (duration: 113.424747ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:56:58.896562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.924825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"}
{"level":"warn","ts":"2026-09-22T02:56:58.896576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.807313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"}
{"level":"info","ts":"2026-09-22T02:56:58.896627Z","caller":"traceutil/trace.go:171","msg":"trace[316714934] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:27003; }","duration":"112.021317ms","start":"2026-09-22T02:56:58.784588Z","end":"2026-09-22T02:56:58.89661Z","steps":["trace[316714934] 'agreement among raft nodes before linearized reading'  (duration: 111.798122ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:58.896686Z","caller":"traceutil/trace.go:171","msg":"trace[923423350] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27003; }","duration":"112.962647ms","start":"2026-09-22T02:56:58.783694Z","end":"2026-09-22T02:56:58.896657Z","steps":["trace[923423350] 'agreement among raft nodes before linearized reading'  (duration: 112.697081ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:56:59.038046Z","caller":"traceutil/trace.go:171","msg":"trace[725411442] transaction","detail":"{read_only:false; response_revision:27005; number_of_response:1; }","duration":"137.065153ms","start":"2026-09-22T02:56:58.900962Z","end":"2026-09-22T02:56:59.038027Z","steps":["trace[725411442] 'process raft request'  (duration: 119.767692ms)","trace[725411442] 'compare'  (duration: 16.979513ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:57:02.319963Z","caller":"traceutil/trace.go:171","msg":"trace[13178583] linearizableReadLoop","detail":"{readStateIndex:27927; appliedIndex:27926; }","duration":"165.412089ms","start":"2026-09-22T02:57:02.154529Z","end":"2026-09-22T02:57:02.319942Z","steps":["trace[13178583] 'read index received'  (duration: 133.363753ms)","trace[13178583] 'applied index is now lower than readState.Index'  (duration: 32.047456ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:57:02.319984Z","caller":"traceutil/trace.go:171","msg":"trace[1768647568] transaction","detail":"{read_only:false; response_revision:27038; number_of_response:1; }","duration":"174.663318ms","start":"2026-09-22T02:57:02.145288Z","end":"2026-09-22T02:57:02.319951Z","steps":["trace[1768647568] 'process raft request'  (duration: 142.580281ms)","trace[1768647568] 'compare'  (duration: 31.959534ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:57:02.320151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.607073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"}
{"level":"info","ts":"2026-09-22T02:57:02.320193Z","caller":"traceutil/trace.go:171","msg":"trace[1460250300] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:27038; }","duration":"165.705436ms","start":"2026-09-22T02:57:02.154475Z","end":"2026-09-22T02:57:02.32018Z","steps":["trace[1460250300] 'agreement among raft nodes before linearized reading'  (duration: 165.541022ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:57:11.818941Z","caller":"traceutil/trace.go:171","msg":"trace[2135430236] transaction","detail":"{read_only:false; response_revision:27104; number_of_response:1; }","duration":"223.192596ms","start":"2026-09-22T02:57:11.59572Z","end":"2026-09-22T02:57:11.818913Z","steps":["trace[2135430236] 'process raft request'  (duration: 222.999922ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:57:12.02792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.486315ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10636553195863851655 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" mod_revision:27090 > success:<request_put:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" value_size:507 >> failure:<request_range:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-22T02:57:12.028365Z","caller":"traceutil/trace.go:171","msg":"trace[1053587835] transaction","detail":"{read_only:false; response_revision:27105; number_of_response:1; }","duration":"349.948817ms","start":"2026-09-22T02:57:11.67837Z","end":"2026-09-22T02:57:12.028318Z","steps":["trace[1053587835] 'process raft request'  (duration: 230.957002ms)","trace[1053587835] 'compare'  (duration: 118.21837ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:57:12.028506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:11.678342Z","time spent":"350.088641ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" mod_revision:27090 > success:<request_put:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" value_size:507 >> failure:<request_range:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" > >"}
{"level":"info","ts":"2026-09-22T02:57:12.028763Z","caller":"traceutil/trace.go:171","msg":"trace[758017052] transaction","detail":"{read_only:false; response_revision:27106; number_of_response:1; }","duration":"114.906507ms","start":"2026-09-22T02:57:11.913833Z","end":"2026-09-22T02:57:12.02874Z","steps":["trace[758017052] 'process raft request'  (duration: 114.260174ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:57:12.221823Z","caller":"traceutil/trace.go:171","msg":"trace[707017913] linearizableReadLoop","detail":"{readStateIndex:28001; appliedIndex:27999; }","duration":"142.923458ms","start":"2026-09-22T02:57:12.078873Z","end":"2026-09-22T02:57:12.221796Z","steps":["trace[707017913] 'read index received'  (duration: 122.995491ms)","trace[707017913] 'applied index is now lower than readState.Index'  (duration: 19.926957ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:57:12.221844Z","caller":"traceutil/trace.go:171","msg":"trace[161171491] transaction","detail":"{read_only:false; response_revision:27109; number_of_response:1; }","duration":"194.707257ms","start":"2026-09-22T02:57:12.027106Z","end":"2026-09-22T02:57:12.221813Z","steps":["trace[161171491] 'process raft request'  (duration: 174.74742ms)","trace[161171491] 'compare'  (duration: 19.753273ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:57:12.221845Z","caller":"traceutil/trace.go:171","msg":"trace[1774560768] transaction","detail":"{read_only:false; response_revision:27110; number_of_response:1; }","duration":"163.58126ms","start":"2026-09-22T02:57:12.058233Z","end":"2026-09-22T02:57:12.221814Z","steps":["trace[1774560768] 'process raft request'  (duration: 163.512908ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:57:12.222047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.155252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-09-22T02:57:12.222082Z","caller":"traceutil/trace.go:171","msg":"trace[835571484] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:27110; }","duration":"143.255795ms","start":"2026-09-22T02:57:12.078816Z","end":"2026-09-22T02:57:12.222072Z","steps":["trace[835571484] 'agreement among raft nodes before linearized reading'  (duration: 143.117051ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:57:14.319227Z","caller":"traceutil/trace.go:171","msg":"trace[66691906] transaction","detail":"{read_only:false; response_revision:27123; number_of_response:1; }","duration":"263.765573ms","start":"2026-09-22T02:57:14.055432Z","end":"2026-09-22T02:57:14.319198Z","steps":["trace[66691906] 'process raft request'  (duration: 263.59849ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:57:14.319528Z","caller":"traceutil/trace.go:171","msg":"trace[670820121] linearizableReadLoop","detail":"{readStateIndex:28013; appliedIndex:28011; }","duration":"267.952084ms","start":"2026-09-22T02:57:14.051538Z","end":"2026-09-22T02:57:14.31949Z","steps":["trace[670820121] 'read index received'  (duration: 72.639855ms)","trace[670820121] 'applied index is now lower than readState.Index'  (duration: 195.310619ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:57:14.319604Z","caller":"traceutil/trace.go:171","msg":"trace[1471782013] transaction","detail":"{read_only:false; response_revision:27122; number_of_response:1; }","duration":"285.028799ms","start":"2026-09-22T02:57:14.034521Z","end":"2026-09-22T02:57:14.31955Z","steps":["trace[1471782013] 'process raft request'  (duration: 230.933672ms)","trace[1471782013] 'compare'  (duration: 52.710928ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:57:14.319729Z","caller":"traceutil/trace.go:171","msg":"trace[516382474] transaction","detail":"{read_only:false; response_revision:27124; number_of_response:1; }","duration":"262.241431ms","start":"2026-09-22T02:57:14.057466Z","end":"2026-09-22T02:57:14.319708Z","steps":["trace[516382474] 'process raft request'  (duration: 261.67926ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:57:14.31985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.28924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-22T02:57:14.319938Z","caller":"traceutil/trace.go:171","msg":"trace[1722512503] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:27124; }","duration":"268.429313ms","start":"2026-09-22T02:57:14.05149Z","end":"2026-09-22T02:57:14.319919Z","steps":["trace[1722512503] 'agreement among raft nodes before linearized reading'  (duration: 268.2654ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:57:14.393521Z","caller":"traceutil/trace.go:171","msg":"trace[158259894] transaction","detail":"{read_only:false; response_revision:27125; number_of_response:1; }","duration":"166.131085ms","start":"2026-09-22T02:57:14.227342Z","end":"2026-09-22T02:57:14.393473Z","steps":["trace[158259894] 'process raft request'  (duration: 165.779718ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:57:14.393774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.598548ms","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":"info","ts":"2026-09-22T02:57:14.393853Z","caller":"traceutil/trace.go:171","msg":"trace[45211891] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:27125; }","duration":"133.747642ms","start":"2026-09-22T02:57:14.260084Z","end":"2026-09-22T02:57:14.393832Z","steps":["trace[45211891] 'agreement among raft nodes before linearized reading'  (duration: 133.597978ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:57:30.299986Z","caller":"traceutil/trace.go:171","msg":"trace[1232946994] transaction","detail":"{read_only:false; response_revision:27244; number_of_response:1; }","duration":"104.69621ms","start":"2026-09-22T02:57:30.195263Z","end":"2026-09-22T02:57:30.299959Z","steps":["trace[1232946994] 'process raft request'  (duration: 104.525906ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:57:30.508029Z","caller":"traceutil/trace.go:171","msg":"trace[473186716] transaction","detail":"{read_only:false; response_revision:27247; number_of_response:1; }","duration":"128.325676ms","start":"2026-09-22T02:57:30.379679Z","end":"2026-09-22T02:57:30.508005Z","steps":["trace[473186716] 'process raft request'  (duration: 128.280395ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:57:30.508066Z","caller":"traceutil/trace.go:171","msg":"trace[598139314] transaction","detail":"{read_only:false; response_revision:27246; number_of_response:1; }","duration":"131.362851ms","start":"2026-09-22T02:57:30.376674Z","end":"2026-09-22T02:57:30.508037Z","steps":["trace[598139314] 'process raft request'  (duration: 131.156716ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:57:30.741088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.230454ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10636553195863852000 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:27229 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-22T02:57:30.741348Z","caller":"traceutil/trace.go:171","msg":"trace[548980275] transaction","detail":"{read_only:false; response_revision:27248; number_of_response:1; }","duration":"290.472445ms","start":"2026-09-22T02:57:30.450772Z","end":"2026-09-22T02:57:30.741244Z","steps":["trace[548980275] 'process raft request'  (duration: 155.008836ms)","trace[548980275] 'compare'  (duration: 135.130181ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:57:30.741602Z","caller":"traceutil/trace.go:171","msg":"trace[273140242] linearizableReadLoop","detail":"{readStateIndex:28143; appliedIndex:28140; }","duration":"234.623161ms","start":"2026-09-22T02:57:30.506623Z","end":"2026-09-22T02:57:30.741246Z","steps":["trace[273140242] 'read index received'  (duration: 1.297379ms)","trace[273140242] 'applied index is now lower than readState.Index'  (duration: 233.324922ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:57:30.741621Z","caller":"traceutil/trace.go:171","msg":"trace[867343509] transaction","detail":"{read_only:false; response_revision:27249; number_of_response:1; }","duration":"149.836076ms","start":"2026-09-22T02:57:30.591763Z","end":"2026-09-22T02:57:30.7416Z","steps":["trace[867343509] 'process raft request'  (duration: 149.439088ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:57:30.741769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.101961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-22T02:57:30.741871Z","caller":"traceutil/trace.go:171","msg":"trace[716873947] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:27249; }","duration":"235.261234ms","start":"2026-09-22T02:57:30.506586Z","end":"2026-09-22T02:57:30.741847Z","steps":["trace[716873947] 'agreement among raft nodes before linearized reading'  (duration: 234.663402ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:57:30.741888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.672326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-22T02:57:30.742043Z","caller":"traceutil/trace.go:171","msg":"trace[762450871] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0; response_count:0; response_revision:27249; }","duration":"209.891991ms","start":"2026-09-22T02:57:30.532129Z","end":"2026-09-22T02:57:30.742021Z","steps":["trace[762450871] 'agreement among raft nodes before linearized reading'  (duration: 209.622556ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:57:31.329359Z","caller":"traceutil/trace.go:171","msg":"trace[1510823485] transaction","detail":"{read_only:false; response_revision:27251; number_of_response:1; }","duration":"249.485348ms","start":"2026-09-22T02:57:31.079832Z","end":"2026-09-22T02:57:31.329317Z","steps":["trace[1510823485] 'process raft request'  (duration: 249.323285ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:57:31.547652Z","caller":"traceutil/trace.go:171","msg":"trace[202736232] transaction","detail":"{read_only:false; response_revision:27252; number_of_response:1; }","duration":"449.065819ms","start":"2026-09-22T02:57:31.098554Z","end":"2026-09-22T02:57:31.54762Z","steps":["trace[202736232] 'process raft request'  (duration: 448.577558ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:57:31.548126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:31.098541Z","time spent":"449.379676ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:27232 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-22T02:57:31.719236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.93918ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10636553195863852008 > lease_revoke:<id:139ca0c6da02c2c9>","response":"size:30"}
{"level":"info","ts":"2026-09-22T02:57:31.719789Z","caller":"traceutil/trace.go:171","msg":"trace[2030596424] linearizableReadLoop","detail":"{readStateIndex:28147; appliedIndex:28145; }","duration":"563.548678ms","start":"2026-09-22T02:57:31.156221Z","end":"2026-09-22T02:57:31.71977Z","steps":["trace[2030596424] 'read index received'  (duration: 172.92518ms)","trace[2030596424] 'applied index is now lower than readState.Index'  (duration: 390.622498ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:57:31.719907Z","caller":"traceutil/trace.go:171","msg":"trace[1863847957] transaction","detail":"{read_only:false; response_revision:27253; number_of_response:1; }","duration":"430.769957ms","start":"2026-09-22T02:57:31.289095Z","end":"2026-09-22T02:57:31.719865Z","steps":["trace[1863847957] 'process raft request'  (duration: 430.263486ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:57:31.720305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:31.289075Z","time spent":"430.91065ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:27173 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:600 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >"}
{"level":"warn","ts":"2026-09-22T02:57:31.867481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.227468ms","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-09-22T02:57:31.867609Z","caller":"traceutil/trace.go:171","msg":"trace[1406247951] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27253; }","duration":"711.399682ms","start":"2026-09-22T02:57:31.156174Z","end":"2026-09-22T02:57:31.867574Z","steps":["trace[1406247951] 'agreement among raft nodes before linearized reading'  (duration: 563.680941ms)","trace[1406247951] 'range keys from in-memory index tree'  (duration: 147.406074ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:57:31.867665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:31.156152Z","time spent":"711.497773ms","remote":"127.0.0.1:39060","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-09-22T02:57:31.868295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.436546ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10636553195863852010 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/endpointslices/openstack/magnum-registry-9wj5q\" mod_revision:27243 > success:<request_put:<key:\"/registry/endpointslices/openstack/magnum-registry-9wj5q\" value_size:1142 >> failure:<request_range:<key:\"/registry/endpointslices/openstack/magnum-registry-9wj5q\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-22T02:57:31.869205Z","caller":"traceutil/trace.go:171","msg":"trace[384917066] transaction","detail":"{read_only:false; response_revision:27254; number_of_response:1; }","duration":"533.873463ms","start":"2026-09-22T02:57:31.335269Z","end":"2026-09-22T02:57:31.869143Z","steps":["trace[384917066] 'process raft request'  (duration: 384.494397ms)","trace[384917066] 'compare'  (duration: 147.785123ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:57:31.86925Z","caller":"traceutil/trace.go:171","msg":"trace[2124374120] transaction","detail":"{read_only:false; response_revision:27255; number_of_response:1; }","duration":"532.812491ms","start":"2026-09-22T02:57:31.336418Z","end":"2026-09-22T02:57:31.869231Z","steps":["trace[2124374120] 'process raft request'  (duration: 532.654217ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:57:31.86937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:31.336406Z","time spent":"532.903112ms","remote":"127.0.0.1:39308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2789,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/replicasets/openstack/magnum-registry-c45778976\" mod_revision:26416 > success:<request_put:<key:\"/registry/replicasets/openstack/magnum-registry-c45778976\" value_size:2724 >> failure:<request_range:<key:\"/registry/replicasets/openstack/magnum-registry-c45778976\" > >"}
{"level":"warn","ts":"2026-09-22T02:57:31.869414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:31.33525Z","time spent":"534.047026ms","remote":"127.0.0.1:39066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1206,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/endpointslices/openstack/magnum-registry-9wj5q\" mod_revision:27243 > success:<request_put:<key:\"/registry/endpointslices/openstack/magnum-registry-9wj5q\" value_size:1142 >> failure:<request_range:<key:\"/registry/endpointslices/openstack/magnum-registry-9wj5q\" > >"}
{"level":"warn","ts":"2026-09-22T02:57:31.869513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.381758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-22T02:57:31.869545Z","caller":"traceutil/trace.go:171","msg":"trace[656914622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27256; }","duration":"301.411228ms","start":"2026-09-22T02:57:31.568124Z","end":"2026-09-22T02:57:31.869535Z","steps":["trace[656914622] 'agreement among raft nodes before linearized reading'  (duration: 301.358708ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:57:31.869524Z","caller":"traceutil/trace.go:171","msg":"trace[1686959696] linearizableReadLoop","detail":"{readStateIndex:28152; appliedIndex:28149; }","duration":"149.637232ms","start":"2026-09-22T02:57:31.719826Z","end":"2026-09-22T02:57:31.869463Z","steps":["trace[1686959696] 'read index received'  (duration: 41.401µs)","trace[1686959696] 'applied index is now lower than readState.Index'  (duration: 149.5923ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-22T02:57:31.86957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:31.568069Z","time spent":"301.496141ms","remote":"127.0.0.1:38816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-09-22T02:57:31.869716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.674246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"}
{"level":"info","ts":"2026-09-22T02:57:31.869757Z","caller":"traceutil/trace.go:171","msg":"trace[995630311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27256; }","duration":"588.766007ms","start":"2026-09-22T02:57:31.280979Z","end":"2026-09-22T02:57:31.869745Z","steps":["trace[995630311] 'agreement among raft nodes before linearized reading'  (duration: 588.586743ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:57:31.869783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:31.280964Z","time spent":"588.813158ms","remote":"127.0.0.1:39060","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-09-22T02:57:31.869758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.571669ms","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-09-22T02:57:31.869877Z","caller":"traceutil/trace.go:171","msg":"trace[442211009] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27256; }","duration":"671.689712ms","start":"2026-09-22T02:57:31.198167Z","end":"2026-09-22T02:57:31.869857Z","steps":["trace[442211009] 'agreement among raft nodes before linearized reading'  (duration: 671.421356ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:57:31.870146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:31.198114Z","time spent":"672.012359ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-09-22T02:57:31.870622Z","caller":"traceutil/trace.go:171","msg":"trace[1929708373] transaction","detail":"{read_only:false; response_revision:27256; number_of_response:1; }","duration":"532.642258ms","start":"2026-09-22T02:57:31.336785Z","end":"2026-09-22T02:57:31.869427Z","steps":["trace[1929708373] 'process raft request'  (duration: 532.382862ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:57:31.870695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.708016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"}
{"level":"info","ts":"2026-09-22T02:57:31.870791Z","caller":"traceutil/trace.go:171","msg":"trace[1011399811] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27256; }","duration":"344.860379ms","start":"2026-09-22T02:57:31.525908Z","end":"2026-09-22T02:57:31.870769Z","steps":["trace[1011399811] 'agreement among raft nodes before linearized reading'  (duration: 343.583481ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:57:31.870809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:31.336759Z","time spent":"533.952564ms","remote":"127.0.0.1:38974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":676,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/services/endpoints/openstack/magnum-registry\" mod_revision:27242 > success:<request_put:<key:\"/registry/services/endpoints/openstack/magnum-registry\" value_size:614 >> failure:<request_range:<key:\"/registry/services/endpoints/openstack/magnum-registry\" > >"}
{"level":"warn","ts":"2026-09-22T02:57:31.870841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:57:31.525889Z","time spent":"344.941621ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"info","ts":"2026-09-22T02:57:50.983092Z","caller":"traceutil/trace.go:171","msg":"trace[1804018179] transaction","detail":"{read_only:false; response_revision:27521; number_of_response:1; }","duration":"111.70842ms","start":"2026-09-22T02:57:50.87136Z","end":"2026-09-22T02:57:50.983068Z","steps":["trace[1804018179] 'process raft request'  (duration: 111.528586ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:57:50.986108Z","caller":"traceutil/trace.go:171","msg":"trace[1889154325] transaction","detail":"{read_only:false; response_revision:27522; number_of_response:1; }","duration":"102.82456ms","start":"2026-09-22T02:57:50.88326Z","end":"2026-09-22T02:57:50.986085Z","steps":["trace[1889154325] 'process raft request'  (duration: 102.661887ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:57:52.106195Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24595}
{"level":"info","ts":"2026-09-22T02:57:52.256861Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24595,"took":"146.73559ms","hash":1993685514,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":37261312,"current-db-size-in-use":"37 MB"}
{"level":"info","ts":"2026-09-22T02:57:52.256933Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1993685514,"revision":24595,"compact-revision":21675}
{"level":"info","ts":"2026-09-22T02:57:53.783084Z","caller":"traceutil/trace.go:171","msg":"trace[874928892] transaction","detail":"{read_only:false; response_revision:27563; number_of_response:1; }","duration":"104.892374ms","start":"2026-09-22T02:57:53.678164Z","end":"2026-09-22T02:57:53.783056Z","steps":["trace[874928892] 'process raft request'  (duration: 104.001935ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:57:54.012813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.070438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-22T02:57:54.012865Z","caller":"traceutil/trace.go:171","msg":"trace[2071403444] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:27563; }","duration":"191.16651ms","start":"2026-09-22T02:57:53.821686Z","end":"2026-09-22T02:57:54.012852Z","steps":["trace[2071403444] 'count revisions from in-memory index tree'  (duration: 191.002917ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:57:54.162718Z","caller":"traceutil/trace.go:171","msg":"trace[670757482] transaction","detail":"{read_only:false; response_revision:27564; number_of_response:1; }","duration":"118.835682ms","start":"2026-09-22T02:57:54.043863Z","end":"2026-09-22T02:57:54.162698Z","steps":["trace[670757482] 'process raft request'  (duration: 118.649348ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-22T02:57:54.180169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.540761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"}
{"level":"info","ts":"2026-09-22T02:57:54.180243Z","caller":"traceutil/trace.go:171","msg":"trace[438514196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27565; }","duration":"100.625573ms","start":"2026-09-22T02:57:54.079601Z","end":"2026-09-22T02:57:54.180226Z","steps":["trace[438514196] 'agreement among raft nodes before linearized reading'  (duration: 100.442889ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:57:54.355969Z","caller":"traceutil/trace.go:171","msg":"trace[795362074] transaction","detail":"{read_only:false; response_revision:27566; number_of_response:1; }","duration":"175.71579ms","start":"2026-09-22T02:57:54.180109Z","end":"2026-09-22T02:57:54.355825Z","steps":["trace[795362074] 'process raft request'  (duration: 160.218018ms)","trace[795362074] 'compare'  (duration: 15.223336ms)"],"step_count":2}
{"level":"info","ts":"2026-09-22T02:57:54.363249Z","caller":"traceutil/trace.go:171","msg":"trace[320487988] transaction","detail":"{read_only:false; response_revision:27569; number_of_response:1; }","duration":"178.484248ms","start":"2026-09-22T02:57:54.184603Z","end":"2026-09-22T02:57:54.363087Z","steps":["trace[320487988] 'process raft request'  (duration: 178.441328ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:57:54.363804Z","caller":"traceutil/trace.go:171","msg":"trace[469427536] transaction","detail":"{read_only:false; response_revision:27567; number_of_response:1; }","duration":"180.308717ms","start":"2026-09-22T02:57:54.18348Z","end":"2026-09-22T02:57:54.363789Z","steps":["trace[469427536] 'process raft request'  (duration: 179.405398ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T02:57:54.364019Z","caller":"traceutil/trace.go:171","msg":"trace[1198066749] transaction","detail":"{read_only:false; response_revision:27568; number_of_response:1; }","duration":"179.774646ms","start":"2026-09-22T02:57:54.184232Z","end":"2026-09-22T02:57:54.364007Z","steps":["trace[1198066749] 'process raft request'  (duration: 178.774985ms)"],"step_count":1}
{"level":"info","ts":"2026-09-22T03:00:35.472357Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d078b1e39c21939c","local-member-applied-index":30010,"local-member-snapshot-index":20009,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-09-22T03:00:35.480376Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30010}
{"level":"info","ts":"2026-09-22T03:00:35.480497Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25010}
{"level":"info","ts":"2026-09-22T03:02:52.113043Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27543}
{"level":"info","ts":"2026-09-22T03:02:52.268206Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27543,"took":"150.765427ms","hash":3627552810,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":35962880,"current-db-size-in-use":"36 MB"}
{"level":"info","ts":"2026-09-22T03:02:52.268292Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3627552810,"revision":27543,"compact-revision":24595}
{"level":"info","ts":"2026-09-22T03:07:52.120252Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30347}
{"level":"info","ts":"2026-09-22T03:07:52.267966Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30347,"took":"144.386654ms","hash":310344320,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":31551488,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-09-22T03:07:52.268051Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":310344320,"revision":30347,"compact-revision":27543}
{"level":"info","ts":"2026-09-22T03:12:52.12765Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32633}
{"level":"info","ts":"2026-09-22T03:12:52.310961Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32633,"took":"178.825383ms","hash":3341082006,"current-db-size-bytes":59932672,"current-db-size":"60 MB","current-db-size-in-use-bytes":30609408,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-09-22T03:12:52.311042Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3341082006,"revision":32633,"compact-revision":30347}
{"level":"info","ts":"2026-09-22T03:17:52.134195Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34855}
{"level":"info","ts":"2026-09-22T03:17:52.263063Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34855,"took":"124.765922ms","hash":3581842766,"current-db-size-bytes":59932672,"current-db-size":"60 MB","current-db-size-in-use-bytes":31563776,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-09-22T03:17:52.263133Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3581842766,"revision":34855,"compact-revision":32633}
{"level":"info","ts":"2026-09-22T03:21:26.110515Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d078b1e39c21939c","local-member-applied-index":40011,"local-member-snapshot-index":30010,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-09-22T03:21:26.115203Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40011}
{"level":"info","ts":"2026-09-22T03:21:26.115328Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35011}
{"level":"info","ts":"2026-09-22T03:22:52.146143Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37154}
{"level":"info","ts":"2026-09-22T03:22:52.288218Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37154,"took":"137.476905ms","hash":559153646,"current-db-size-bytes":61624320,"current-db-size":"62 MB","current-db-size-in-use-bytes":33341440,"current-db-size-in-use":"33 MB"}
{"level":"info","ts":"2026-09-22T03:22:52.288329Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":559153646,"revision":37154,"compact-revision":34855}
{"level":"info","ts":"2026-09-22T03:27:52.154625Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39424}
{"level":"info","ts":"2026-09-22T03:27:52.289124Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39424,"took":"130.533716ms","hash":973927052,"current-db-size-bytes":61624320,"current-db-size":"62 MB","current-db-size-in-use-bytes":30498816,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-09-22T03:27:52.28925Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":973927052,"revision":39424,"compact-revision":37154}
{"level":"info","ts":"2026-09-22T03:32:52.161715Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41655}
{"level":"info","ts":"2026-09-22T03:32:52.285706Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41655,"took":"120.002159ms","hash":3701732891,"current-db-size-bytes":61624320,"current-db-size":"62 MB","current-db-size-in-use-bytes":29216768,"current-db-size-in-use":"29 MB"}
{"level":"info","ts":"2026-09-22T03:32:52.285802Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3701732891,"revision":41655,"compact-revision":39424}
{"level":"info","ts":"2026-09-22T03:37:52.171194Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":43877}
{"level":"info","ts":"2026-09-22T03:37:52.306501Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":43877,"took":"128.593065ms","hash":3966409167,"current-db-size-bytes":61624320,"current-db-size":"62 MB","current-db-size-in-use-bytes":29089792,"current-db-size-in-use":"29 MB"}
{"level":"info","ts":"2026-09-22T03:37:52.306574Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3966409167,"revision":43877,"compact-revision":41655}