{"level":"warn","ts":"2026-05-25T00:43:59.547482Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-05-25T00:43:59.5476Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]}
{"level":"warn","ts":"2026-05-25T00:43:59.547706Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-05-25T00:43:59.547733Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]}
{"level":"info","ts":"2026-05-25T00:43:59.547765Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-05-25T00:43:59.548902Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]}
{"level":"info","ts":"2026-05-25T00:43:59.549046Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"}
{"level":"info","ts":"2026-05-25T00:43:59.552487Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.090688ms"}
{"level":"info","ts":"2026-05-25T00:43:59.55773Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"}
{"level":"info","ts":"2026-05-25T00:43:59.557798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"}
{"level":"info","ts":"2026-05-25T00:43:59.557813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"}
{"level":"info","ts":"2026-05-25T00:43:59.557827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-05-25T00:43:59.557833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"}
{"level":"info","ts":"2026-05-25T00:43:59.55786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"}
{"level":"warn","ts":"2026-05-25T00:43:59.566926Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-05-25T00:43:59.56825Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-05-25T00:43:59.569366Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"}
{"level":"info","ts":"2026-05-25T00:43:59.572169Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-05-25T00:43:59.572398Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-05-25T00:43:59.57251Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"}
{"level":"info","ts":"2026-05-25T00:43:59.572657Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"}
{"level":"info","ts":"2026-05-25T00:43:59.572677Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"}
{"level":"info","ts":"2026-05-25T00:43:59.573059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"}
{"level":"info","ts":"2026-05-25T00:43:59.573182Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]}
{"level":"info","ts":"2026-05-25T00:43:59.577566Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-05-25T00:43:59.577614Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"}
{"level":"info","ts":"2026-05-25T00:43:59.577667Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"}
{"level":"info","ts":"2026-05-25T00:43:59.578615Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-05-25T00:43:59.578675Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-05-25T00:44:00.55889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"}
{"level":"info","ts":"2026-05-25T00:44:00.558941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"}
{"level":"info","ts":"2026-05-25T00:44:00.558974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"}
{"level":"info","ts":"2026-05-25T00:44:00.558988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"}
{"level":"info","ts":"2026-05-25T00:44:00.558996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"}
{"level":"info","ts":"2026-05-25T00:44:00.559007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"}
{"level":"info","ts":"2026-05-25T00:44:00.559017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"}
{"level":"info","ts":"2026-05-25T00:44:00.559876Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-05-25T00:44:00.560585Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"}
{"level":"info","ts":"2026-05-25T00:44:00.56063Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-05-25T00:44:00.560816Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-05-25T00:44:00.561138Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"}
{"level":"info","ts":"2026-05-25T00:44:00.561237Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-05-25T00:44:00.561151Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-05-25T00:44:00.561265Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-05-25T00:44:00.56127Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-05-25T00:44:00.564236Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-05-25T00:44:00.564296Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"}
{"level":"info","ts":"2026-05-25T00:44:26.63793Z","caller":"traceutil/trace.go:171","msg":"trace[1906726928] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"240.077115ms","start":"2026-05-25T00:44:26.397821Z","end":"2026-05-25T00:44:26.637899Z","steps":["trace[1906726928] 'process raft request'  (duration: 239.905061ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T00:44:42.915734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.032555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"warn","ts":"2026-05-25T00:44:42.915782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.791532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-05-25T00:44:42.915869Z","caller":"traceutil/trace.go:171","msg":"trace[922564478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"139.325442ms","start":"2026-05-25T00:44:42.776522Z","end":"2026-05-25T00:44:42.915847Z","steps":["trace[922564478] 'range keys from in-memory index tree'  (duration: 138.850582ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T00:44:42.915903Z","caller":"traceutil/trace.go:171","msg":"trace[1064204665] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"187.046827ms","start":"2026-05-25T00:44:42.728826Z","end":"2026-05-25T00:44:42.915873Z","steps":["trace[1064204665] 'range keys from in-memory index tree'  (duration: 186.630218ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T00:44:43.309552Z","caller":"traceutil/trace.go:171","msg":"trace[459254539] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"389.187906ms","start":"2026-05-25T00:44:42.920318Z","end":"2026-05-25T00:44:43.309506Z","steps":["trace[459254539] 'process raft request'  (duration: 290.001254ms)","trace[459254539] 'compare'  (duration: 98.758603ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T00:44:43.310284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:44:42.920284Z","time spent":"389.35189ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:577 > 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":"info","ts":"2026-05-25T00:44:43.386684Z","caller":"traceutil/trace.go:171","msg":"trace[717311416] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"395.928955ms","start":"2026-05-25T00:44:42.990724Z","end":"2026-05-25T00:44:43.386653Z","steps":["trace[717311416] 'process raft request'  (duration: 395.596817ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T00:44:43.387428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:44:42.990683Z","time spent":"396.080818ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:444 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:600 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >"}
{"level":"info","ts":"2026-05-25T00:44:43.388074Z","caller":"traceutil/trace.go:171","msg":"trace[1031582014] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:615; }","duration":"204.587332ms","start":"2026-05-25T00:44:43.183423Z","end":"2026-05-25T00:44:43.38801Z","steps":["trace[1031582014] 'read index received'  (duration: 26.946711ms)","trace[1031582014] 'applied index is now lower than readState.Index'  (duration: 177.639351ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T00:44:43.388125Z","caller":"traceutil/trace.go:171","msg":"trace[1755096135] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"347.327119ms","start":"2026-05-25T00:44:43.040761Z","end":"2026-05-25T00:44:43.388088Z","steps":["trace[1755096135] 'process raft request'  (duration: 345.899528ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T00:44:43.388245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:44:43.040723Z","time spent":"347.451332ms","remote":"127.0.0.1:44972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036273,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" mod_revision:590 > success:<request_put:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" value_size:1036189 >> failure:<request_range:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" > >"}
{"level":"warn","ts":"2026-05-25T00:44:43.388825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.383429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fs4sk\" ","response":"range_response_count:1 size:16965"}
{"level":"info","ts":"2026-05-25T00:44:43.38892Z","caller":"traceutil/trace.go:171","msg":"trace[212354557] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fs4sk; range_end:; response_count:1; response_revision:595; }","duration":"205.507452ms","start":"2026-05-25T00:44:43.183386Z","end":"2026-05-25T00:44:43.388893Z","steps":["trace[212354557] 'agreement among raft nodes before linearized reading'  (duration: 204.817497ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T00:45:29.96245Z","caller":"traceutil/trace.go:171","msg":"trace[813919303] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"129.730913ms","start":"2026-05-25T00:45:29.832689Z","end":"2026-05-25T00:45:29.96242Z","steps":["trace[813919303] 'process raft request'  (duration: 129.565859ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T00:46:13.998192Z","caller":"traceutil/trace.go:171","msg":"trace[445515981] linearizableReadLoop","detail":"{readStateIndex:1887; appliedIndex:1886; }","duration":"180.997605ms","start":"2026-05-25T00:46:13.81717Z","end":"2026-05-25T00:46:13.998167Z","steps":["trace[445515981] 'read index received'  (duration: 180.857282ms)","trace[445515981] 'applied index is now lower than readState.Index'  (duration: 139.133µs)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T00:46:13.998466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.268171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"}
{"level":"info","ts":"2026-05-25T00:46:13.998532Z","caller":"traceutil/trace.go:171","msg":"trace[398921843] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1814; }","duration":"181.375703ms","start":"2026-05-25T00:46:13.817124Z","end":"2026-05-25T00:46:13.998499Z","steps":["trace[398921843] 'agreement among raft nodes before linearized reading'  (duration: 181.158809ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T00:46:13.998648Z","caller":"traceutil/trace.go:171","msg":"trace[700975160] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"253.709303ms","start":"2026-05-25T00:46:13.744865Z","end":"2026-05-25T00:46:13.998574Z","steps":["trace[700975160] 'process raft request'  (duration: 253.152691ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T00:46:52.946703Z","caller":"traceutil/trace.go:171","msg":"trace[567708670] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"147.361861ms","start":"2026-05-25T00:46:52.799295Z","end":"2026-05-25T00:46:52.946657Z","steps":["trace[567708670] 'process raft request'  (duration: 123.392577ms)","trace[567708670] 'compare'  (duration: 23.756129ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T00:46:58.703136Z","caller":"traceutil/trace.go:171","msg":"trace[2044669021] linearizableReadLoop","detail":"{readStateIndex:2194; appliedIndex:2193; }","duration":"222.754568ms","start":"2026-05-25T00:46:58.480351Z","end":"2026-05-25T00:46:58.703106Z","steps":["trace[2044669021] 'read index received'  (duration: 222.483992ms)","trace[2044669021] 'applied index is now lower than readState.Index'  (duration: 269.356µs)"],"step_count":2}
{"level":"info","ts":"2026-05-25T00:46:58.703215Z","caller":"traceutil/trace.go:171","msg":"trace[3742422] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"253.411565ms","start":"2026-05-25T00:46:58.449758Z","end":"2026-05-25T00:46:58.70317Z","steps":["trace[3742422] 'process raft request'  (duration: 253.127289ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T00:46:58.703558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.166626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"}
{"level":"info","ts":"2026-05-25T00:46:58.703677Z","caller":"traceutil/trace.go:171","msg":"trace[409267201] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2113; }","duration":"223.32808ms","start":"2026-05-25T00:46:58.480322Z","end":"2026-05-25T00:46:58.70365Z","steps":["trace[409267201] 'agreement among raft nodes before linearized reading'  (duration: 222.904361ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T00:46:59.06619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.301659ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10836960723862476321 > lease_revoke:<id:16649e5c96aed558>","response":"size:28"}
{"level":"info","ts":"2026-05-25T00:46:59.066381Z","caller":"traceutil/trace.go:171","msg":"trace[274968174] linearizableReadLoop","detail":"{readStateIndex:2195; appliedIndex:2194; }","duration":"265.825022ms","start":"2026-05-25T00:46:58.800529Z","end":"2026-05-25T00:46:59.066354Z","steps":["trace[274968174] 'read index received'  (duration: 22.207267ms)","trace[274968174] 'applied index is now lower than readState.Index'  (duration: 243.612815ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T00:46:59.066902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.334973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-05-25T00:46:59.067038Z","caller":"traceutil/trace.go:171","msg":"trace[130733449] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2113; }","duration":"266.543827ms","start":"2026-05-25T00:46:58.800466Z","end":"2026-05-25T00:46:59.06701Z","steps":["trace[130733449] 'agreement among raft nodes before linearized reading'  (duration: 265.963585ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T00:46:59.07105Z","caller":"traceutil/trace.go:171","msg":"trace[1379577613] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"157.389047ms","start":"2026-05-25T00:46:58.913634Z","end":"2026-05-25T00:46:59.071023Z","steps":["trace[1379577613] 'process raft request'  (duration: 157.232803ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T00:48:26.824271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.113612ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10836960723862477915 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:2771 > 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\" > >>","response":"size:16"}
{"level":"info","ts":"2026-05-25T00:48:26.824444Z","caller":"traceutil/trace.go:171","msg":"trace[1386317579] linearizableReadLoop","detail":"{readStateIndex:2891; appliedIndex:2890; }","duration":"132.829138ms","start":"2026-05-25T00:48:26.69159Z","end":"2026-05-25T00:48:26.824419Z","steps":["trace[1386317579] 'read index received'  (duration: 7.338498ms)","trace[1386317579] 'applied index is now lower than readState.Index'  (duration: 125.4889ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T00:48:26.824514Z","caller":"traceutil/trace.go:171","msg":"trace[1208377574] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"170.74176ms","start":"2026-05-25T00:48:26.653722Z","end":"2026-05-25T00:48:26.824464Z","steps":["trace[1208377574] 'process raft request'  (duration: 45.297171ms)","trace[1208377574] 'compare'  (duration: 124.928968ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T00:48:26.825004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.40991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"}
{"level":"info","ts":"2026-05-25T00:48:26.825069Z","caller":"traceutil/trace.go:171","msg":"trace[1957131757] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2782; }","duration":"133.510672ms","start":"2026-05-25T00:48:26.691537Z","end":"2026-05-25T00:48:26.825047Z","steps":["trace[1957131757] 'agreement among raft nodes before linearized reading'  (duration: 132.94267ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T00:48:27.340267Z","caller":"traceutil/trace.go:171","msg":"trace[163308690] transaction","detail":"{read_only:false; response_revision:2784; number_of_response:1; }","duration":"170.785841ms","start":"2026-05-25T00:48:27.169447Z","end":"2026-05-25T00:48:27.340232Z","steps":["trace[163308690] 'process raft request'  (duration: 170.514245ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T00:50:52.590939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.828214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"info","ts":"2026-05-25T00:50:52.591057Z","caller":"traceutil/trace.go:171","msg":"trace[1498461613] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3748; }","duration":"115.000247ms","start":"2026-05-25T00:50:52.476037Z","end":"2026-05-25T00:50:52.591037Z","steps":["trace[1498461613] 'range keys from in-memory index tree'  (duration: 114.541397ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T00:50:52.705786Z","caller":"traceutil/trace.go:171","msg":"trace[1657208672] transaction","detail":"{read_only:false; response_revision:3751; number_of_response:1; }","duration":"111.210046ms","start":"2026-05-25T00:50:52.594546Z","end":"2026-05-25T00:50:52.705756Z","steps":["trace[1657208672] 'process raft request'  (duration: 111.129175ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T00:50:52.705941Z","caller":"traceutil/trace.go:171","msg":"trace[74552365] transaction","detail":"{read_only:false; response_revision:3750; number_of_response:1; }","duration":"115.255452ms","start":"2026-05-25T00:50:52.590618Z","end":"2026-05-25T00:50:52.705873Z","steps":["trace[74552365] 'process raft request'  (duration: 114.596338ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T00:52:08.46087Z","caller":"traceutil/trace.go:171","msg":"trace[1280733828] linearizableReadLoop","detail":"{readStateIndex:4897; appliedIndex:4896; }","duration":"120.664366ms","start":"2026-05-25T00:52:08.340169Z","end":"2026-05-25T00:52:08.460833Z","steps":["trace[1280733828] 'read index received'  (duration: 111.538309ms)","trace[1280733828] 'applied index is now lower than readState.Index'  (duration: 9.124497ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T00:52:08.460993Z","caller":"traceutil/trace.go:171","msg":"trace[110146451] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"127.710218ms","start":"2026-05-25T00:52:08.333271Z","end":"2026-05-25T00:52:08.460981Z","steps":["trace[110146451] 'process raft request'  (duration: 118.357726ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T00:52:08.46143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.249568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-638c87aa-c659-4ce6-8e83-4e733b70f08a\" ","response":"range_response_count:1 size:3774"}
{"level":"info","ts":"2026-05-25T00:52:08.461465Z","caller":"traceutil/trace.go:171","msg":"trace[1817066347] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-638c87aa-c659-4ce6-8e83-4e733b70f08a; range_end:; response_count:1; response_revision:4715; }","duration":"121.298699ms","start":"2026-05-25T00:52:08.340157Z","end":"2026-05-25T00:52:08.461456Z","steps":["trace[1817066347] 'agreement among raft nodes before linearized reading'  (duration: 121.168327ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T00:52:13.244281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.689322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-05-25T00:52:13.244345Z","caller":"traceutil/trace.go:171","msg":"trace[1995601390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4914; }","duration":"109.793695ms","start":"2026-05-25T00:52:13.134539Z","end":"2026-05-25T00:52:13.244333Z","steps":["trace[1995601390] 'range keys from in-memory index tree'  (duration: 109.528479ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T00:52:13.244797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.798296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-05-25T00:52:13.244825Z","caller":"traceutil/trace.go:171","msg":"trace[397934087] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4914; }","duration":"109.859037ms","start":"2026-05-25T00:52:13.13496Z","end":"2026-05-25T00:52:13.244819Z","steps":["trace[397934087] 'range keys from in-memory index tree'  (duration: 109.690344ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T00:52:13.382641Z","caller":"traceutil/trace.go:171","msg":"trace[822762432] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"127.207294ms","start":"2026-05-25T00:52:13.255403Z","end":"2026-05-25T00:52:13.38261Z","steps":["trace[822762432] 'process raft request'  (duration: 126.673843ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T00:52:13.385831Z","caller":"traceutil/trace.go:171","msg":"trace[324903587] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"130.005843ms","start":"2026-05-25T00:52:13.255799Z","end":"2026-05-25T00:52:13.385805Z","steps":["trace[324903587] 'process raft request'  (duration: 129.501343ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T00:52:22.661875Z","caller":"traceutil/trace.go:171","msg":"trace[497191808] transaction","detail":"{read_only:false; response_revision:5107; number_of_response:1; }","duration":"101.789561ms","start":"2026-05-25T00:52:22.56005Z","end":"2026-05-25T00:52:22.66184Z","steps":["trace[497191808] 'process raft request'  (duration: 99.631853ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T00:52:22.664518Z","caller":"traceutil/trace.go:171","msg":"trace[151418931] transaction","detail":"{read_only:false; response_revision:5109; number_of_response:1; }","duration":"102.409324ms","start":"2026-05-25T00:52:22.562083Z","end":"2026-05-25T00:52:22.664493Z","steps":["trace[151418931] 'process raft request'  (duration: 99.675165ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T00:54:00.588817Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2967}
{"level":"info","ts":"2026-05-25T00:54:00.66023Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2967,"took":"69.887444ms","hash":1984252232,"current-db-size-bytes":59154432,"current-db-size":"59 MB","current-db-size-in-use-bytes":34295808,"current-db-size-in-use":"34 MB"}
{"level":"info","ts":"2026-05-25T00:54:00.660323Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1984252232,"revision":2967,"compact-revision":-1}
{"level":"info","ts":"2026-05-25T00:58:25.264613Z","caller":"traceutil/trace.go:171","msg":"trace[473591878] transaction","detail":"{read_only:false; response_revision:8095; number_of_response:1; }","duration":"267.133827ms","start":"2026-05-25T00:58:24.997444Z","end":"2026-05-25T00:58:25.264578Z","steps":["trace[473591878] 'process raft request'  (duration: 266.940713ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T00:58:25.387294Z","caller":"traceutil/trace.go:171","msg":"trace[302837012] transaction","detail":"{read_only:false; response_revision:8096; number_of_response:1; }","duration":"118.048801ms","start":"2026-05-25T00:58:25.269211Z","end":"2026-05-25T00:58:25.38726Z","steps":["trace[302837012] 'process raft request'  (duration: 75.219342ms)","trace[302837012] 'compare'  (duration: 42.587984ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T00:58:25.38753Z","caller":"traceutil/trace.go:171","msg":"trace[67548610] transaction","detail":"{read_only:false; response_revision:8097; number_of_response:1; }","duration":"118.190604ms","start":"2026-05-25T00:58:25.269279Z","end":"2026-05-25T00:58:25.387469Z","steps":["trace[67548610] 'process raft request'  (duration: 117.873208ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T00:58:36.341937Z","caller":"traceutil/trace.go:171","msg":"trace[496047181] transaction","detail":"{read_only:false; response_revision:8152; number_of_response:1; }","duration":"145.234764ms","start":"2026-05-25T00:58:36.196676Z","end":"2026-05-25T00:58:36.341911Z","steps":["trace[496047181] 'process raft request'  (duration: 144.882337ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T00:59:00.594467Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6115}
{"level":"info","ts":"2026-05-25T00:59:00.695648Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6115,"took":"99.110645ms","hash":43141679,"current-db-size-bytes":59154432,"current-db-size":"59 MB","current-db-size-in-use-bytes":23228416,"current-db-size-in-use":"23 MB"}
{"level":"info","ts":"2026-05-25T00:59:00.695733Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":43141679,"revision":6115,"compact-revision":2967}
{"level":"info","ts":"2026-05-25T00:59:16.454409Z","caller":"traceutil/trace.go:171","msg":"trace[1587127160] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8460; }","duration":"129.25528ms","start":"2026-05-25T00:59:16.325127Z","end":"2026-05-25T00:59:16.454382Z","steps":["trace[1587127160] 'process raft request'  (duration: 112.152814ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T00:59:16.600141Z","caller":"traceutil/trace.go:171","msg":"trace[125151164] transaction","detail":"{read_only:false; response_revision:8461; number_of_response:1; }","duration":"274.466174ms","start":"2026-05-25T00:59:16.325644Z","end":"2026-05-25T00:59:16.60011Z","steps":["trace[125151164] 'process raft request'  (duration: 261.033796ms)","trace[125151164] 'compare'  (duration: 13.283265ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T00:59:16.600215Z","caller":"traceutil/trace.go:171","msg":"trace[104369760] linearizableReadLoop","detail":"{readStateIndex:8765; appliedIndex:8763; }","duration":"221.630061ms","start":"2026-05-25T00:59:16.378556Z","end":"2026-05-25T00:59:16.600186Z","steps":["trace[104369760] 'read index received'  (duration: 58.743119ms)","trace[104369760] 'applied index is now lower than readState.Index'  (duration: 162.884002ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T00:59:16.600361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.789724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-25T00:59:16.600403Z","caller":"traceutil/trace.go:171","msg":"trace[513944546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8462; }","duration":"221.888656ms","start":"2026-05-25T00:59:16.378505Z","end":"2026-05-25T00:59:16.600394Z","steps":["trace[513944546] 'agreement among raft nodes before linearized reading'  (duration: 221.793654ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:00:04.933497Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023fa.wal"}
{"level":"info","ts":"2026-05-25T01:01:32.078343Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ab97322ebdd79664","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-05-25T01:01:32.086569Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-05-25T01:01:32.086672Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"info","ts":"2026-05-25T01:04:00.604183Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8331}
{"level":"info","ts":"2026-05-25T01:04:00.680678Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8331,"took":"74.929145ms","hash":148272728,"current-db-size-bytes":59154432,"current-db-size":"59 MB","current-db-size-in-use-bytes":23805952,"current-db-size-in-use":"24 MB"}
{"level":"info","ts":"2026-05-25T01:04:00.680749Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":148272728,"revision":8331,"compact-revision":6115}
{"level":"info","ts":"2026-05-25T01:06:00.166418Z","caller":"traceutil/trace.go:171","msg":"trace[2069788831] transaction","detail":"{read_only:false; response_revision:11614; number_of_response:1; }","duration":"103.950095ms","start":"2026-05-25T01:06:00.062442Z","end":"2026-05-25T01:06:00.166392Z","steps":["trace[2069788831] 'process raft request'  (duration: 103.792881ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:06:00.290512Z","caller":"traceutil/trace.go:171","msg":"trace[231768103] transaction","detail":"{read_only:false; response_revision:11615; number_of_response:1; }","duration":"214.492621ms","start":"2026-05-25T01:06:00.075986Z","end":"2026-05-25T01:06:00.290479Z","steps":["trace[231768103] 'process raft request'  (duration: 175.394564ms)","trace[231768103] 'compare'  (duration: 38.881632ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:06:00.290703Z","caller":"traceutil/trace.go:171","msg":"trace[810364079] linearizableReadLoop","detail":"{readStateIndex:12019; appliedIndex:12017; }","duration":"199.166602ms","start":"2026-05-25T01:06:00.0915Z","end":"2026-05-25T01:06:00.290667Z","steps":["trace[810364079] 'read index received'  (duration: 74.79481ms)","trace[810364079] 'applied index is now lower than readState.Index'  (duration: 124.369932ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:06:00.290958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.450728ms","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-05-25T01:06:00.291004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.688557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-25T01:06:00.291048Z","caller":"traceutil/trace.go:171","msg":"trace[525175356] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11616; }","duration":"199.54647ms","start":"2026-05-25T01:06:00.091479Z","end":"2026-05-25T01:06:00.291026Z","steps":["trace[525175356] 'agreement among raft nodes before linearized reading'  (duration: 199.334386ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:06:00.291051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.256765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"}
{"level":"info","ts":"2026-05-25T01:06:00.291089Z","caller":"traceutil/trace.go:171","msg":"trace[289538289] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:11616; }","duration":"109.83264ms","start":"2026-05-25T01:06:00.181235Z","end":"2026-05-25T01:06:00.291067Z","steps":["trace[289538289] 'agreement among raft nodes before linearized reading'  (duration: 109.657387ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:06:00.291142Z","caller":"traceutil/trace.go:171","msg":"trace[1183716107] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11616; }","duration":"193.385628ms","start":"2026-05-25T01:06:00.097733Z","end":"2026-05-25T01:06:00.291118Z","steps":["trace[1183716107] 'agreement among raft nodes before linearized reading'  (duration: 193.153573ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:06:00.291145Z","caller":"traceutil/trace.go:171","msg":"trace[1318557879] transaction","detail":"{read_only:false; response_revision:11616; number_of_response:1; }","duration":"127.037429ms","start":"2026-05-25T01:06:00.163959Z","end":"2026-05-25T01:06:00.290997Z","steps":["trace[1318557879] 'process raft request'  (duration: 126.470267ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:09:00.610094Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10535}
{"level":"info","ts":"2026-05-25T01:09:00.685759Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10535,"took":"73.546106ms","hash":2001881782,"current-db-size-bytes":59154432,"current-db-size":"59 MB","current-db-size-in-use-bytes":25968640,"current-db-size-in-use":"26 MB"}
{"level":"info","ts":"2026-05-25T01:09:00.68582Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2001881782,"revision":10535,"compact-revision":8331}
{"level":"info","ts":"2026-05-25T01:10:22.25112Z","caller":"traceutil/trace.go:171","msg":"trace[1818495195] transaction","detail":"{read_only:false; response_revision:13392; number_of_response:1; }","duration":"226.214865ms","start":"2026-05-25T01:10:22.024836Z","end":"2026-05-25T01:10:22.251051Z","steps":["trace[1818495195] 'process raft request'  (duration: 197.998821ms)","trace[1818495195] 'compare'  (duration: 27.839676ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:10:57.506684Z","caller":"traceutil/trace.go:171","msg":"trace[951543229] linearizableReadLoop","detail":"{readStateIndex:14107; appliedIndex:14106; }","duration":"126.560192ms","start":"2026-05-25T01:10:57.380098Z","end":"2026-05-25T01:10:57.506658Z","steps":["trace[951543229] 'read index received'  (duration: 126.367728ms)","trace[951543229] 'applied index is now lower than readState.Index'  (duration: 191.584µs)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:10:57.506763Z","caller":"traceutil/trace.go:171","msg":"trace[1147747479] transaction","detail":"{read_only:false; response_revision:13634; number_of_response:1; }","duration":"235.058835ms","start":"2026-05-25T01:10:57.271667Z","end":"2026-05-25T01:10:57.506726Z","steps":["trace[1147747479] 'process raft request'  (duration: 234.852031ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:10:57.506849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.740226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-25T01:10:57.506885Z","caller":"traceutil/trace.go:171","msg":"trace[1230330677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13634; }","duration":"126.831888ms","start":"2026-05-25T01:10:57.380042Z","end":"2026-05-25T01:10:57.506874Z","steps":["trace[1230330677] 'agreement among raft nodes before linearized reading'  (duration: 126.724135ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:12:08.847284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.795576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-05-25T01:12:08.847384Z","caller":"traceutil/trace.go:171","msg":"trace[849640133] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:14109; }","duration":"182.954299ms","start":"2026-05-25T01:12:08.664409Z","end":"2026-05-25T01:12:08.847363Z","steps":["trace[849640133] 'count revisions from in-memory index tree'  (duration: 182.653303ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:12:22.763198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.95441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-05-25T01:12:22.763274Z","caller":"traceutil/trace.go:171","msg":"trace[336706336] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:14196; }","duration":"268.080032ms","start":"2026-05-25T01:12:22.495179Z","end":"2026-05-25T01:12:22.763259Z","steps":["trace[336706336] 'count revisions from in-memory index tree'  (duration: 267.851597ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:12:22.763342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.146304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"warn","ts":"2026-05-25T01:12:22.763369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.887089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"info","ts":"2026-05-25T01:12:22.763397Z","caller":"traceutil/trace.go:171","msg":"trace[1992978337] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:14196; }","duration":"162.232895ms","start":"2026-05-25T01:12:22.601152Z","end":"2026-05-25T01:12:22.763385Z","steps":["trace[1992978337] 'count revisions from in-memory index tree'  (duration: 162.087962ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:12:22.763443Z","caller":"traceutil/trace.go:171","msg":"trace[2143413625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14196; }","duration":"135.009322ms","start":"2026-05-25T01:12:22.628415Z","end":"2026-05-25T01:12:22.763424Z","steps":["trace[2143413625] 'range keys from in-memory index tree'  (duration: 134.695676ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:12:23.668415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.719205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"}
{"level":"warn","ts":"2026-05-25T01:12:23.668474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.192801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-25T01:12:23.668534Z","caller":"traceutil/trace.go:171","msg":"trace[105699253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14199; }","duration":"225.898758ms","start":"2026-05-25T01:12:23.442608Z","end":"2026-05-25T01:12:23.668506Z","steps":["trace[105699253] 'range keys from in-memory index tree'  (duration: 225.451389ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:12:23.66855Z","caller":"traceutil/trace.go:171","msg":"trace[1334635519] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14199; }","duration":"314.304973ms","start":"2026-05-25T01:12:23.354223Z","end":"2026-05-25T01:12:23.668528Z","steps":["trace[1334635519] 'range keys from in-memory index tree'  (duration: 314.112509ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:12:23.668598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:12:23.354204Z","time spent":"314.384816ms","remote":"127.0.0.1:45302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "}
{"level":"warn","ts":"2026-05-25T01:12:23.668601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.973112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-25T01:12:23.668656Z","caller":"traceutil/trace.go:171","msg":"trace[1878383894] linearizableReadLoop","detail":"{readStateIndex:14694; appliedIndex:14693; }","duration":"178.509965ms","start":"2026-05-25T01:12:23.490128Z","end":"2026-05-25T01:12:23.668638Z","steps":["trace[1878383894] 'read index received'  (duration: 151.70954ms)","trace[1878383894] 'applied index is now lower than readState.Index'  (duration: 26.799555ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:12:23.668759Z","caller":"traceutil/trace.go:171","msg":"trace[85431744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14199; }","duration":"289.161156ms","start":"2026-05-25T01:12:23.379573Z","end":"2026-05-25T01:12:23.668734Z","steps":["trace[85431744] 'range keys from in-memory index tree'  (duration: 288.9112ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:12:23.668775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.73223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/openstack/ovn-controller\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-25T01:12:23.668799Z","caller":"traceutil/trace.go:171","msg":"trace[398279630] range","detail":"{range_begin:/registry/daemonsets/openstack/ovn-controller; range_end:; response_count:0; response_revision:14200; }","duration":"144.792812ms","start":"2026-05-25T01:12:23.523998Z","end":"2026-05-25T01:12:23.668791Z","steps":["trace[398279630] 'agreement among raft nodes before linearized reading'  (duration: 144.739751ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:12:23.668859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.717269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"info","ts":"2026-05-25T01:12:23.668917Z","caller":"traceutil/trace.go:171","msg":"trace[1030279240] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14200; }","duration":"178.80545ms","start":"2026-05-25T01:12:23.490097Z","end":"2026-05-25T01:12:23.668902Z","steps":["trace[1030279240] 'agreement among raft nodes before linearized reading'  (duration: 178.635347ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:12:23.66882Z","caller":"traceutil/trace.go:171","msg":"trace[1540736264] transaction","detail":"{read_only:false; response_revision:14200; number_of_response:1; }","duration":"182.691405ms","start":"2026-05-25T01:12:23.486096Z","end":"2026-05-25T01:12:23.668787Z","steps":["trace[1540736264] 'process raft request'  (duration: 155.725547ms)","trace[1540736264] 'compare'  (duration: 26.518578ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:12:32.213871Z","caller":"traceutil/trace.go:171","msg":"trace[1663079613] transaction","detail":"{read_only:false; response_revision:14355; number_of_response:1; }","duration":"148.038942ms","start":"2026-05-25T01:12:32.065791Z","end":"2026-05-25T01:12:32.21383Z","steps":["trace[1663079613] 'process raft request'  (duration: 147.879028ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:12:32.485119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.912789ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10836960723862509061 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:14251 > 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:16"}
{"level":"info","ts":"2026-05-25T01:12:32.485249Z","caller":"traceutil/trace.go:171","msg":"trace[1765977779] linearizableReadLoop","detail":"{readStateIndex:14854; appliedIndex:14852; }","duration":"183.767257ms","start":"2026-05-25T01:12:32.301466Z","end":"2026-05-25T01:12:32.485234Z","steps":["trace[1765977779] 'read index received'  (duration: 28.687034ms)","trace[1765977779] 'applied index is now lower than readState.Index'  (duration: 155.079513ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:12:32.485301Z","caller":"traceutil/trace.go:171","msg":"trace[1019733824] transaction","detail":"{read_only:false; response_revision:14357; number_of_response:1; }","duration":"267.391298ms","start":"2026-05-25T01:12:32.21789Z","end":"2026-05-25T01:12:32.485281Z","steps":["trace[1019733824] 'process raft request'  (duration: 267.308657ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:12:32.485296Z","caller":"traceutil/trace.go:171","msg":"trace[1220858535] transaction","detail":"{read_only:false; response_revision:14356; number_of_response:1; }","duration":"267.554763ms","start":"2026-05-25T01:12:32.217698Z","end":"2026-05-25T01:12:32.485253Z","steps":["trace[1220858535] 'process raft request'  (duration: 112.442359ms)","trace[1220858535] 'compare'  (duration: 154.815957ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:12:32.485377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.91659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e70c89dd-7a85-45ea-ad5d-d3851fc39080\" ","response":"range_response_count:1 size:3607"}
{"level":"info","ts":"2026-05-25T01:12:32.485404Z","caller":"traceutil/trace.go:171","msg":"trace[1794576509] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e70c89dd-7a85-45ea-ad5d-d3851fc39080; range_end:; response_count:1; response_revision:14357; }","duration":"183.979792ms","start":"2026-05-25T01:12:32.301416Z","end":"2026-05-25T01:12:32.485395Z","steps":["trace[1794576509] 'agreement among raft nodes before linearized reading'  (duration: 183.853139ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:13:35.302028Z","caller":"traceutil/trace.go:171","msg":"trace[1944342827] transaction","detail":"{read_only:false; response_revision:15243; number_of_response:1; }","duration":"104.232985ms","start":"2026-05-25T01:13:35.197752Z","end":"2026-05-25T01:13:35.301985Z","steps":["trace[1944342827] 'process raft request'  (duration: 103.611842ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:14:00.615655Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12811}
{"level":"info","ts":"2026-05-25T01:14:00.68955Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12811,"took":"71.293219ms","hash":2738838783,"current-db-size-bytes":59154432,"current-db-size":"59 MB","current-db-size-in-use-bytes":30425088,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-05-25T01:14:00.689619Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2738838783,"revision":12811,"compact-revision":10535}
{"level":"info","ts":"2026-05-25T01:19:00.622171Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15381}
{"level":"info","ts":"2026-05-25T01:19:00.719904Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15381,"took":"93.894133ms","hash":1063184513,"current-db-size-bytes":59154432,"current-db-size":"59 MB","current-db-size-in-use-bytes":32219136,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-05-25T01:19:00.71998Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1063184513,"revision":15381,"compact-revision":12811}
{"level":"info","ts":"2026-05-25T01:19:48.772881Z","caller":"traceutil/trace.go:171","msg":"trace[1565080494] transaction","detail":"{read_only:false; response_revision:18147; number_of_response:1; }","duration":"125.4196ms","start":"2026-05-25T01:19:48.647429Z","end":"2026-05-25T01:19:48.772849Z","steps":["trace[1565080494] 'process raft request'  (duration: 125.041001ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:19:53.452275Z","caller":"traceutil/trace.go:171","msg":"trace[42220686] transaction","detail":"{read_only:false; response_revision:18190; number_of_response:1; }","duration":"288.591398ms","start":"2026-05-25T01:19:53.163657Z","end":"2026-05-25T01:19:53.452248Z","steps":["trace[42220686] 'process raft request'  (duration: 288.374814ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:19:53.625674Z","caller":"traceutil/trace.go:171","msg":"trace[1115898935] transaction","detail":"{read_only:false; response_revision:18191; number_of_response:1; }","duration":"458.240446ms","start":"2026-05-25T01:19:53.167408Z","end":"2026-05-25T01:19:53.625649Z","steps":["trace[1115898935] 'process raft request'  (duration: 458.037402ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:19:53.625859Z","caller":"traceutil/trace.go:171","msg":"trace[2001824866] linearizableReadLoop","detail":"{readStateIndex:18811; appliedIndex:18808; }","duration":"246.413684ms","start":"2026-05-25T01:19:53.379414Z","end":"2026-05-25T01:19:53.625828Z","steps":["trace[2001824866] 'read index received'  (duration: 72.672449ms)","trace[2001824866] 'applied index is now lower than readState.Index'  (duration: 173.739625ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:19:53.625869Z","caller":"traceutil/trace.go:171","msg":"trace[1758563914] transaction","detail":"{read_only:false; response_revision:18192; number_of_response:1; }","duration":"426.972806ms","start":"2026-05-25T01:19:53.198871Z","end":"2026-05-25T01:19:53.625844Z","steps":["trace[1758563914] 'process raft request'  (duration: 426.730831ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:19:53.625909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:19:53.167394Z","time spent":"458.4424ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:18180 > 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":"warn","ts":"2026-05-25T01:19:53.625998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.579887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:19:53.626035Z","caller":"traceutil/trace.go:171","msg":"trace[778319370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18192; }","duration":"246.663049ms","start":"2026-05-25T01:19:53.379363Z","end":"2026-05-25T01:19:53.626026Z","steps":["trace[778319370] 'agreement among raft nodes before linearized reading'  (duration: 246.589458ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:19:53.626946Z","caller":"traceutil/trace.go:171","msg":"trace[879825142] transaction","detail":"{read_only:false; response_revision:18193; number_of_response:1; }","duration":"169.990094ms","start":"2026-05-25T01:19:53.456918Z","end":"2026-05-25T01:19:53.626908Z","steps":["trace[879825142] 'process raft request'  (duration: 169.892572ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:19:53.627002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.264655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-crpjv\" ","response":"range_response_count:1 size:9797"}
{"level":"info","ts":"2026-05-25T01:19:53.627408Z","caller":"traceutil/trace.go:171","msg":"trace[1926040348] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-crpjv; range_end:; response_count:1; response_revision:18193; }","duration":"228.712825ms","start":"2026-05-25T01:19:53.39868Z","end":"2026-05-25T01:19:53.627393Z","steps":["trace[1926040348] 'agreement among raft nodes before linearized reading'  (duration: 228.204484ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:19:53.626671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:19:53.198838Z","time spent":"427.099528ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:18181 > 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-05-25T01:22:16.571738Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ab97322ebdd79664","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-05-25T01:22:16.576173Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002}
{"level":"info","ts":"2026-05-25T01:22:16.576262Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}
{"level":"info","ts":"2026-05-25T01:24:00.627628Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17621}
{"level":"info","ts":"2026-05-25T01:24:00.725116Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17621,"took":"93.678448ms","hash":1456798186,"current-db-size-bytes":59154432,"current-db-size":"59 MB","current-db-size-in-use-bytes":33886208,"current-db-size-in-use":"34 MB"}
{"level":"info","ts":"2026-05-25T01:24:00.725178Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1456798186,"revision":17621,"compact-revision":15381}
{"level":"info","ts":"2026-05-25T01:24:11.052749Z","caller":"traceutil/trace.go:171","msg":"trace[477493534] transaction","detail":"{read_only:false; response_revision:20134; number_of_response:1; }","duration":"252.338627ms","start":"2026-05-25T01:24:10.800386Z","end":"2026-05-25T01:24:11.052725Z","steps":["trace[477493534] 'process raft request'  (duration: 252.187484ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:24:11.060196Z","caller":"traceutil/trace.go:171","msg":"trace[1369652945] transaction","detail":"{read_only:false; response_revision:20135; number_of_response:1; }","duration":"146.443638ms","start":"2026-05-25T01:24:10.913722Z","end":"2026-05-25T01:24:11.060166Z","steps":["trace[1369652945] 'process raft request'  (duration: 145.909687ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:24:11.371419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.431356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"}
{"level":"info","ts":"2026-05-25T01:24:11.371507Z","caller":"traceutil/trace.go:171","msg":"trace[2051180578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20136; }","duration":"214.561978ms","start":"2026-05-25T01:24:11.156927Z","end":"2026-05-25T01:24:11.371489Z","steps":["trace[2051180578] 'range keys from in-memory index tree'  (duration: 214.189251ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:24:11.371792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.660363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"}
{"level":"info","ts":"2026-05-25T01:24:11.371839Z","caller":"traceutil/trace.go:171","msg":"trace[1034881717] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20136; }","duration":"209.729924ms","start":"2026-05-25T01:24:11.162096Z","end":"2026-05-25T01:24:11.371826Z","steps":["trace[1034881717] 'range keys from in-memory index tree'  (duration: 209.569751ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:24:11.372111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.469176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:24:11.372165Z","caller":"traceutil/trace.go:171","msg":"trace[291107752] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:20136; }","duration":"172.554907ms","start":"2026-05-25T01:24:11.199594Z","end":"2026-05-25T01:24:11.372149Z","steps":["trace[291107752] 'count revisions from in-memory index tree'  (duration: 172.416115ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:24:11.620411Z","caller":"traceutil/trace.go:171","msg":"trace[2050945586] transaction","detail":"{read_only:false; response_revision:20138; number_of_response:1; }","duration":"243.61406ms","start":"2026-05-25T01:24:11.376772Z","end":"2026-05-25T01:24:11.620386Z","steps":["trace[2050945586] 'process raft request'  (duration: 198.377371ms)","trace[2050945586] 'compare'  (duration: 45.088906ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:24:11.873095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.751018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"}
{"level":"info","ts":"2026-05-25T01:24:11.873166Z","caller":"traceutil/trace.go:171","msg":"trace[1773695937] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20138; }","duration":"149.856111ms","start":"2026-05-25T01:24:11.723294Z","end":"2026-05-25T01:24:11.87315Z","steps":["trace[1773695937] 'range keys from in-memory index tree'  (duration: 149.609535ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:24:12.291014Z","caller":"traceutil/trace.go:171","msg":"trace[1506074048] linearizableReadLoop","detail":"{readStateIndex:20829; appliedIndex:20828; }","duration":"128.842281ms","start":"2026-05-25T01:24:12.162148Z","end":"2026-05-25T01:24:12.29099Z","steps":["trace[1506074048] 'read index received'  (duration: 128.617466ms)","trace[1506074048] 'applied index is now lower than readState.Index'  (duration: 223.765µs)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:24:12.291223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.056846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"}
{"level":"info","ts":"2026-05-25T01:24:12.291234Z","caller":"traceutil/trace.go:171","msg":"trace[936742916] transaction","detail":"{read_only:false; response_revision:20140; number_of_response:1; }","duration":"173.124509ms","start":"2026-05-25T01:24:12.118087Z","end":"2026-05-25T01:24:12.291211Z","steps":["trace[936742916] 'process raft request'  (duration: 172.764171ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:24:12.291264Z","caller":"traceutil/trace.go:171","msg":"trace[945011665] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20140; }","duration":"129.150868ms","start":"2026-05-25T01:24:12.162103Z","end":"2026-05-25T01:24:12.291254Z","steps":["trace[945011665] 'agreement among raft nodes before linearized reading'  (duration: 128.978824ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:27:51.175317Z","caller":"traceutil/trace.go:171","msg":"trace[883676800] linearizableReadLoop","detail":"{readStateIndex:23258; appliedIndex:23257; }","duration":"262.043146ms","start":"2026-05-25T01:27:50.913248Z","end":"2026-05-25T01:27:51.175291Z","steps":["trace[883676800] 'read index received'  (duration: 262.008325ms)","trace[883676800] 'applied index is now lower than readState.Index'  (duration: 33.801µs)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:27:51.175493Z","caller":"traceutil/trace.go:171","msg":"trace[982026464] transaction","detail":"{read_only:false; response_revision:22494; number_of_response:1; }","duration":"304.192329ms","start":"2026-05-25T01:27:50.871262Z","end":"2026-05-25T01:27:51.175455Z","steps":["trace[982026464] 'process raft request'  (duration: 303.877843ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:27:51.175594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.329122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"}
{"level":"info","ts":"2026-05-25T01:27:51.175638Z","caller":"traceutil/trace.go:171","msg":"trace[1589922095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22494; }","duration":"262.430114ms","start":"2026-05-25T01:27:50.913196Z","end":"2026-05-25T01:27:51.175626Z","steps":["trace[1589922095] 'agreement among raft nodes before linearized reading'  (duration: 262.218699ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:27:51.175677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:27:50.871244Z","time spent":"304.349052ms","remote":"127.0.0.1:37378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3015,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/cilium.io/ciliumendpoints/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager-bdfc6fdd8-rnftk\" mod_revision:0 > success:<request_put:<key:\"/registry/cilium.io/ciliumendpoints/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager-bdfc6fdd8-rnftk\" value_size:2875 >> failure:<>"}
{"level":"info","ts":"2026-05-25T01:27:51.176361Z","caller":"traceutil/trace.go:171","msg":"trace[901363280] transaction","detail":"{read_only:false; response_revision:22495; number_of_response:1; }","duration":"166.071819ms","start":"2026-05-25T01:27:51.010257Z","end":"2026-05-25T01:27:51.176329Z","steps":["trace[901363280] 'process raft request'  (duration: 165.855464ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:27:51.333569Z","caller":"traceutil/trace.go:171","msg":"trace[187025181] linearizableReadLoop","detail":"{readStateIndex:23261; appliedIndex:23260; }","duration":"122.27099ms","start":"2026-05-25T01:27:51.211276Z","end":"2026-05-25T01:27:51.333547Z","steps":["trace[187025181] 'read index received'  (duration: 80.238059ms)","trace[187025181] 'applied index is now lower than readState.Index'  (duration: 42.031811ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:27:51.333736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.429554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:27:51.333782Z","caller":"traceutil/trace.go:171","msg":"trace[297712395] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:22497; }","duration":"122.545626ms","start":"2026-05-25T01:27:51.211222Z","end":"2026-05-25T01:27:51.333768Z","steps":["trace[297712395] 'agreement among raft nodes before linearized reading'  (duration: 122.422274ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:27:51.334008Z","caller":"traceutil/trace.go:171","msg":"trace[1878138517] transaction","detail":"{read_only:false; response_revision:22497; number_of_response:1; }","duration":"154.389158ms","start":"2026-05-25T01:27:51.179589Z","end":"2026-05-25T01:27:51.333978Z","steps":["trace[1878138517] 'process raft request'  (duration: 111.901046ms)","trace[1878138517] 'compare'  (duration: 41.952079ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:29:00.652464Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20081}
{"level":"info","ts":"2026-05-25T01:29:00.807178Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20081,"took":"150.924405ms","hash":694993208,"current-db-size-bytes":59154432,"current-db-size":"59 MB","current-db-size-in-use-bytes":41496576,"current-db-size-in-use":"42 MB"}
{"level":"info","ts":"2026-05-25T01:29:00.80723Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":694993208,"revision":20081,"compact-revision":17621}
{"level":"info","ts":"2026-05-25T01:29:12.219016Z","caller":"traceutil/trace.go:171","msg":"trace[1992598332] transaction","detail":"{read_only:false; response_revision:23357; number_of_response:1; }","duration":"130.324442ms","start":"2026-05-25T01:29:12.088657Z","end":"2026-05-25T01:29:12.218981Z","steps":["trace[1992598332] 'process raft request'  (duration: 130.105238ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:29:12.238402Z","caller":"traceutil/trace.go:171","msg":"trace[1322378075] transaction","detail":"{read_only:false; response_revision:23358; number_of_response:1; }","duration":"127.529783ms","start":"2026-05-25T01:29:12.110847Z","end":"2026-05-25T01:29:12.238377Z","steps":["trace[1322378075] 'process raft request'  (duration: 127.165635ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:03.313038Z","caller":"traceutil/trace.go:171","msg":"trace[112490194] transaction","detail":"{read_only:false; response_revision:24492; number_of_response:1; }","duration":"159.281983ms","start":"2026-05-25T01:31:03.153733Z","end":"2026-05-25T01:31:03.313015Z","steps":["trace[112490194] 'process raft request'  (duration: 159.12111ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:07.444238Z","caller":"traceutil/trace.go:171","msg":"trace[109572165] transaction","detail":"{read_only:false; response_revision:24525; number_of_response:1; }","duration":"111.740174ms","start":"2026-05-25T01:31:07.33198Z","end":"2026-05-25T01:31:07.44372Z","steps":["trace[109572165] 'process raft request'  (duration: 111.500759ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:07.655849Z","caller":"traceutil/trace.go:171","msg":"trace[664606329] transaction","detail":"{read_only:false; response_revision:24526; number_of_response:1; }","duration":"242.081646ms","start":"2026-05-25T01:31:07.413729Z","end":"2026-05-25T01:31:07.655811Z","steps":["trace[664606329] 'process raft request'  (duration: 191.778968ms)","trace[664606329] 'compare'  (duration: 50.114024ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:07.655973Z","caller":"traceutil/trace.go:171","msg":"trace[1313101250] linearizableReadLoop","detail":"{readStateIndex:25344; appliedIndex:25343; }","duration":"208.18976ms","start":"2026-05-25T01:31:07.44776Z","end":"2026-05-25T01:31:07.655949Z","steps":["trace[1313101250] 'read index received'  (duration: 157.762549ms)","trace[1313101250] 'applied index is now lower than readState.Index'  (duration: 50.426231ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:31:07.656199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.426555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:31:07.656238Z","caller":"traceutil/trace.go:171","msg":"trace[411180665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24527; }","duration":"208.478917ms","start":"2026-05-25T01:31:07.44775Z","end":"2026-05-25T01:31:07.656229Z","steps":["trace[411180665] 'agreement among raft nodes before linearized reading'  (duration: 208.364614ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:07.656428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.870991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"}
{"level":"info","ts":"2026-05-25T01:31:07.656457Z","caller":"traceutil/trace.go:171","msg":"trace[1861098607] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24527; }","duration":"162.899531ms","start":"2026-05-25T01:31:07.493549Z","end":"2026-05-25T01:31:07.656448Z","steps":["trace[1861098607] 'agreement among raft nodes before linearized reading'  (duration: 162.809869ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:07.800294Z","caller":"traceutil/trace.go:171","msg":"trace[1249643547] linearizableReadLoop","detail":"{readStateIndex:25346; appliedIndex:25345; }","duration":"114.415371ms","start":"2026-05-25T01:31:07.685846Z","end":"2026-05-25T01:31:07.800261Z","steps":["trace[1249643547] 'read index received'  (duration: 91.189774ms)","trace[1249643547] 'applied index is now lower than readState.Index'  (duration: 23.224407ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:07.800421Z","caller":"traceutil/trace.go:171","msg":"trace[404760813] transaction","detail":"{read_only:false; response_revision:24528; number_of_response:1; }","duration":"124.656621ms","start":"2026-05-25T01:31:07.675694Z","end":"2026-05-25T01:31:07.800351Z","steps":["trace[404760813] 'process raft request'  (duration: 101.303311ms)","trace[404760813] 'compare'  (duration: 23.120165ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:31:07.800524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.654617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"}
{"level":"info","ts":"2026-05-25T01:31:07.800573Z","caller":"traceutil/trace.go:171","msg":"trace[137261300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24528; }","duration":"114.748228ms","start":"2026-05-25T01:31:07.685811Z","end":"2026-05-25T01:31:07.800559Z","steps":["trace[137261300] 'agreement among raft nodes before linearized reading'  (duration: 114.574405ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:08.049184Z","caller":"traceutil/trace.go:171","msg":"trace[439302364] transaction","detail":"{read_only:false; response_revision:24529; number_of_response:1; }","duration":"244.579531ms","start":"2026-05-25T01:31:07.804579Z","end":"2026-05-25T01:31:08.049159Z","steps":["trace[439302364] 'process raft request'  (duration: 244.448158ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:08.267895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.134274ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10836960723862540170 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:24517 > success:<request_put:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" value_size:440 >> failure:<request_range:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-25T01:31:08.267991Z","caller":"traceutil/trace.go:171","msg":"trace[1663873449] transaction","detail":"{read_only:false; response_revision:24530; number_of_response:1; }","duration":"210.110422ms","start":"2026-05-25T01:31:08.057867Z","end":"2026-05-25T01:31:08.267977Z","steps":["trace[1663873449] 'process raft request'  (duration: 96.817634ms)","trace[1663873449] 'compare'  (duration: 113.014872ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:08.278989Z","caller":"traceutil/trace.go:171","msg":"trace[2046059613] transaction","detail":"{read_only:false; response_revision:24531; number_of_response:1; }","duration":"216.97968ms","start":"2026-05-25T01:31:08.061985Z","end":"2026-05-25T01:31:08.278964Z","steps":["trace[2046059613] 'process raft request'  (duration: 216.750445ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:08.279168Z","caller":"traceutil/trace.go:171","msg":"trace[1167744505] transaction","detail":"{read_only:false; response_revision:24532; number_of_response:1; }","duration":"214.489646ms","start":"2026-05-25T01:31:08.064643Z","end":"2026-05-25T01:31:08.279133Z","steps":["trace[1167744505] 'process raft request'  (duration: 214.257341ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:09.959451Z","caller":"traceutil/trace.go:171","msg":"trace[1642911918] linearizableReadLoop","detail":"{readStateIndex:25361; appliedIndex:25360; }","duration":"131.664281ms","start":"2026-05-25T01:31:09.827764Z","end":"2026-05-25T01:31:09.959428Z","steps":["trace[1642911918] 'read index received'  (duration: 131.456757ms)","trace[1642911918] 'applied index is now lower than readState.Index'  (duration: 206.434µs)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:31:09.959816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.015549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"}
{"level":"info","ts":"2026-05-25T01:31:09.959925Z","caller":"traceutil/trace.go:171","msg":"trace[1586897971] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:24542; }","duration":"132.170631ms","start":"2026-05-25T01:31:09.827736Z","end":"2026-05-25T01:31:09.959907Z","steps":["trace[1586897971] 'agreement among raft nodes before linearized reading'  (duration: 131.789183ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:09.960348Z","caller":"traceutil/trace.go:171","msg":"trace[948644841] transaction","detail":"{read_only:false; response_revision:24542; number_of_response:1; }","duration":"151.790962ms","start":"2026-05-25T01:31:09.80854Z","end":"2026-05-25T01:31:09.960331Z","steps":["trace[948644841] 'process raft request'  (duration: 150.75003ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:10.564687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.775868ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10836960723862540202 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:24530 > success:<request_put:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" value_size:441 >> failure:<request_range:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-25T01:31:10.564792Z","caller":"traceutil/trace.go:171","msg":"trace[137920233] transaction","detail":"{read_only:false; response_revision:24544; number_of_response:1; }","duration":"291.598038ms","start":"2026-05-25T01:31:10.273179Z","end":"2026-05-25T01:31:10.564777Z","steps":["trace[137920233] 'process raft request'  (duration: 50.668376ms)","trace[137920233] 'compare'  (duration: 240.674436ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:31:10.899266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.237005ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10836960723862540203 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:24531 > 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-05-25T01:31:10.899544Z","caller":"traceutil/trace.go:171","msg":"trace[242359936] linearizableReadLoop","detail":"{readStateIndex:25365; appliedIndex:25362; }","duration":"613.76908ms","start":"2026-05-25T01:31:10.285722Z","end":"2026-05-25T01:31:10.899491Z","steps":["trace[242359936] 'read index received'  (duration: 38.132008ms)","trace[242359936] 'applied index is now lower than readState.Index'  (duration: 575.636172ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:10.899489Z","caller":"traceutil/trace.go:171","msg":"trace[451314469] transaction","detail":"{read_only:false; response_revision:24546; number_of_response:1; }","duration":"615.974898ms","start":"2026-05-25T01:31:10.283492Z","end":"2026-05-25T01:31:10.899467Z","steps":["trace[451314469] 'process raft request'  (duration: 615.871166ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:10.899539Z","caller":"traceutil/trace.go:171","msg":"trace[191958423] transaction","detail":"{read_only:false; response_revision:24545; number_of_response:1; }","duration":"616.014948ms","start":"2026-05-25T01:31:10.283458Z","end":"2026-05-25T01:31:10.899473Z","steps":["trace[191958423] 'process raft request'  (duration: 418.468636ms)","trace[191958423] 'compare'  (duration: 197.079012ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:31:10.899664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:10.283479Z","time spent":"616.116031ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:24532 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:459 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"warn","ts":"2026-05-25T01:31:10.899725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:10.283436Z","time spent":"616.195482ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:24531 > 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-05-25T01:31:10.899847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.128388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"}
{"level":"info","ts":"2026-05-25T01:31:10.899887Z","caller":"traceutil/trace.go:171","msg":"trace[564069210] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24547; }","duration":"614.194439ms","start":"2026-05-25T01:31:10.285681Z","end":"2026-05-25T01:31:10.899875Z","steps":["trace[564069210] 'agreement among raft nodes before linearized reading'  (duration: 613.940583ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:10.899897Z","caller":"traceutil/trace.go:171","msg":"trace[842164818] transaction","detail":"{read_only:false; response_revision:24547; number_of_response:1; }","duration":"204.791438ms","start":"2026-05-25T01:31:10.695091Z","end":"2026-05-25T01:31:10.899883Z","steps":["trace[842164818] 'process raft request'  (duration: 204.366429ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:10.89992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:10.285666Z","time spent":"614.24579ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"info","ts":"2026-05-25T01:31:10.899953Z","caller":"traceutil/trace.go:171","msg":"trace[277582107] transaction","detail":"{read_only:false; response_revision:24548; number_of_response:1; }","duration":"203.614462ms","start":"2026-05-25T01:31:10.69631Z","end":"2026-05-25T01:31:10.899925Z","steps":["trace[277582107] 'process raft request'  (duration: 203.420988ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:10.90011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.459358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"warn","ts":"2026-05-25T01:31:10.900129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.626357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:31:10.900161Z","caller":"traceutil/trace.go:171","msg":"trace[626814743] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:24549; }","duration":"589.55013ms","start":"2026-05-25T01:31:10.310598Z","end":"2026-05-25T01:31:10.900148Z","steps":["trace[626814743] 'agreement among raft nodes before linearized reading'  (duration: 589.459978ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:10.900195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:10.310575Z","time spent":"589.611862ms","remote":"127.0.0.1:45236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "}
{"level":"warn","ts":"2026-05-25T01:31:10.900277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.441874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"}
{"level":"warn","ts":"2026-05-25T01:31:10.900271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.92184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"}
{"level":"info","ts":"2026-05-25T01:31:10.900329Z","caller":"traceutil/trace.go:171","msg":"trace[32523368] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24549; }","duration":"467.540717ms","start":"2026-05-25T01:31:10.432773Z","end":"2026-05-25T01:31:10.900314Z","steps":["trace[32523368] 'agreement among raft nodes before linearized reading'  (duration: 467.370922ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:10.900355Z","caller":"traceutil/trace.go:171","msg":"trace[304378165] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24549; }","duration":"451.031223ms","start":"2026-05-25T01:31:10.449299Z","end":"2026-05-25T01:31:10.900331Z","steps":["trace[304378165] 'agreement among raft nodes before linearized reading'  (duration: 450.809657ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:10.900369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:10.432753Z","time spent":"467.604749ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"warn","ts":"2026-05-25T01:31:10.900394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:10.449287Z","time spent":"451.099435ms","remote":"127.0.0.1:45302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11860,"request content":"key:\"/registry/deployments/openstack/glance-api\" "}
{"level":"warn","ts":"2026-05-25T01:31:10.90044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.548302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:31:10.90048Z","caller":"traceutil/trace.go:171","msg":"trace[1458898458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24549; }","duration":"519.616203ms","start":"2026-05-25T01:31:10.380854Z","end":"2026-05-25T01:31:10.90047Z","steps":["trace[1458898458] 'agreement among raft nodes before linearized reading'  (duration: 519.553762ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:10.900169Z","caller":"traceutil/trace.go:171","msg":"trace[1835479381] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:24549; }","duration":"143.733629ms","start":"2026-05-25T01:31:10.756425Z","end":"2026-05-25T01:31:10.900159Z","steps":["trace[1835479381] 'agreement among raft nodes before linearized reading'  (duration: 143.666388ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:10.900507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:10.380831Z","time spent":"519.670475ms","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-05-25T01:31:10.900555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.731427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:31:10.900595Z","caller":"traceutil/trace.go:171","msg":"trace[534466509] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:24549; }","duration":"449.831238ms","start":"2026-05-25T01:31:10.450752Z","end":"2026-05-25T01:31:10.900583Z","steps":["trace[534466509] 'agreement among raft nodes before linearized reading'  (duration: 449.767657ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:10.900627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:10.450736Z","time spent":"449.883009ms","remote":"127.0.0.1:43300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "}
{"level":"warn","ts":"2026-05-25T01:31:10.900768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.10879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:31:10.900808Z","caller":"traceutil/trace.go:171","msg":"trace[444346864] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:24549; }","duration":"300.193012ms","start":"2026-05-25T01:31:10.600601Z","end":"2026-05-25T01:31:10.900794Z","steps":["trace[444346864] 'agreement among raft nodes before linearized reading'  (duration: 300.132641ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:10.900838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:10.600571Z","time spent":"300.259433ms","remote":"127.0.0.1:46014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "}
{"level":"info","ts":"2026-05-25T01:31:11.259838Z","caller":"traceutil/trace.go:171","msg":"trace[512109324] linearizableReadLoop","detail":"{readStateIndex:25369; appliedIndex:25368; }","duration":"350.52565ms","start":"2026-05-25T01:31:10.909289Z","end":"2026-05-25T01:31:11.259815Z","steps":["trace[512109324] 'read index received'  (duration: 350.363046ms)","trace[512109324] 'applied index is now lower than readState.Index'  (duration: 161.354µs)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:11.259917Z","caller":"traceutil/trace.go:171","msg":"trace[735565008] transaction","detail":"{read_only:false; response_revision:24550; number_of_response:1; }","duration":"351.344338ms","start":"2026-05-25T01:31:10.908533Z","end":"2026-05-25T01:31:11.259877Z","steps":["trace[735565008] 'process raft request'  (duration: 351.114883ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:11.260052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.741404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"}
{"level":"info","ts":"2026-05-25T01:31:11.260087Z","caller":"traceutil/trace.go:171","msg":"trace[346721601] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24550; }","duration":"350.817426ms","start":"2026-05-25T01:31:10.909261Z","end":"2026-05-25T01:31:11.260078Z","steps":["trace[346721601] 'agreement among raft nodes before linearized reading'  (duration: 350.667553ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:11.260114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:10.909253Z","time spent":"350.854547ms","remote":"127.0.0.1:45302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11860,"request content":"key:\"/registry/deployments/openstack/glance-api\" "}
{"level":"warn","ts":"2026-05-25T01:31:11.2601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:10.908512Z","time spent":"351.476211ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:24534 > 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-05-25T01:31:11.283851Z","caller":"traceutil/trace.go:171","msg":"trace[1258283582] transaction","detail":"{read_only:false; response_revision:24552; number_of_response:1; }","duration":"372.069581ms","start":"2026-05-25T01:31:10.911735Z","end":"2026-05-25T01:31:11.283805Z","steps":["trace[1258283582] 'process raft request'  (duration: 371.971989ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:11.283897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.270067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-05-25T01:31:11.283902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.700183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"}
{"level":"info","ts":"2026-05-25T01:31:11.283951Z","caller":"traceutil/trace.go:171","msg":"trace[870531929] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:24552; }","duration":"331.37872ms","start":"2026-05-25T01:31:10.952558Z","end":"2026-05-25T01:31:11.283936Z","steps":["trace[870531929] 'agreement among raft nodes before linearized reading'  (duration: 331.243447ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:11.283969Z","caller":"traceutil/trace.go:171","msg":"trace[1330772723] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24552; }","duration":"371.808125ms","start":"2026-05-25T01:31:10.912141Z","end":"2026-05-25T01:31:11.28395Z","steps":["trace[1330772723] 'agreement among raft nodes before linearized reading'  (duration: 371.617071ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:11.283985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:10.95254Z","time spent":"331.435821ms","remote":"127.0.0.1:48600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true "}
{"level":"warn","ts":"2026-05-25T01:31:11.284017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:10.912122Z","time spent":"371.883097ms","remote":"127.0.0.1:45064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "}
{"level":"warn","ts":"2026-05-25T01:31:11.284029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:10.911728Z","time spent":"372.214374ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:24533 > 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":"info","ts":"2026-05-25T01:31:11.28416Z","caller":"traceutil/trace.go:171","msg":"trace[1258722308] transaction","detail":"{read_only:false; response_revision:24551; number_of_response:1; }","duration":"373.646046ms","start":"2026-05-25T01:31:10.910487Z","end":"2026-05-25T01:31:11.284133Z","steps":["trace[1258722308] 'process raft request'  (duration: 373.079213ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:11.28431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:10.910479Z","time spent":"373.750408ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:24537 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-05-25T01:31:11.518396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.344887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:31:11.5185Z","caller":"traceutil/trace.go:171","msg":"trace[1966468800] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24552; }","duration":"141.46088ms","start":"2026-05-25T01:31:11.377015Z","end":"2026-05-25T01:31:11.518476Z","steps":["trace[1966468800] 'range keys from in-memory index tree'  (duration: 141.274946ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:11.864866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.741634ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10836960723862540220 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:24539 > 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\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-25T01:31:11.864982Z","caller":"traceutil/trace.go:171","msg":"trace[315142701] linearizableReadLoop","detail":"{readStateIndex:25373; appliedIndex:25372; }","duration":"292.230151ms","start":"2026-05-25T01:31:11.572733Z","end":"2026-05-25T01:31:11.864963Z","steps":["trace[315142701] 'read index received'  (duration: 61.1516ms)","trace[315142701] 'applied index is now lower than readState.Index'  (duration: 231.077221ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:31:11.865101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.384263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"}
{"level":"info","ts":"2026-05-25T01:31:11.865125Z","caller":"traceutil/trace.go:171","msg":"trace[1939099281] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24554; }","duration":"292.461425ms","start":"2026-05-25T01:31:11.572657Z","end":"2026-05-25T01:31:11.865118Z","steps":["trace[1939099281] 'agreement among raft nodes before linearized reading'  (duration: 292.348833ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:11.8654Z","caller":"traceutil/trace.go:171","msg":"trace[1003882732] transaction","detail":"{read_only:false; response_revision:24554; number_of_response:1; }","duration":"342.877746ms","start":"2026-05-25T01:31:11.52248Z","end":"2026-05-25T01:31:11.865358Z","steps":["trace[1003882732] 'process raft request'  (duration: 111.527519ms)","trace[1003882732] 'compare'  (duration: 230.427117ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:31:11.865506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:11.522465Z","time spent":"342.991958ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:24539 > 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-05-25T01:31:12.059087Z","caller":"traceutil/trace.go:171","msg":"trace[1847312880] transaction","detail":"{read_only:false; response_revision:24555; number_of_response:1; }","duration":"390.67828ms","start":"2026-05-25T01:31:11.668372Z","end":"2026-05-25T01:31:12.05905Z","steps":["trace[1847312880] 'process raft request'  (duration: 371.040649ms)","trace[1847312880] 'compare'  (duration: 19.311494ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:12.059227Z","caller":"traceutil/trace.go:171","msg":"trace[1138161858] linearizableReadLoop","detail":"{readStateIndex:25375; appliedIndex:25373; }","duration":"191.325229ms","start":"2026-05-25T01:31:11.867877Z","end":"2026-05-25T01:31:12.059202Z","steps":["trace[1138161858] 'read index received'  (duration: 171.550275ms)","trace[1138161858] 'applied index is now lower than readState.Index'  (duration: 19.774044ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:31:12.059268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:11.668339Z","time spent":"390.835823ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/capi-system/controller-leader-election-capi\" mod_revision:24541 > 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-05-25T01:31:12.059354Z","caller":"traceutil/trace.go:171","msg":"trace[805743277] transaction","detail":"{read_only:false; response_revision:24556; number_of_response:1; }","duration":"390.501546ms","start":"2026-05-25T01:31:11.668841Z","end":"2026-05-25T01:31:12.059343Z","steps":["trace[805743277] 'process raft request'  (duration: 390.093907ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:12.059459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:11.668807Z","time spent":"390.576818ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:24540 > 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":"warn","ts":"2026-05-25T01:31:12.059697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.809069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18572"}
{"level":"info","ts":"2026-05-25T01:31:12.059767Z","caller":"traceutil/trace.go:171","msg":"trace[795728002] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24556; }","duration":"191.89608ms","start":"2026-05-25T01:31:11.867855Z","end":"2026-05-25T01:31:12.059751Z","steps":["trace[795728002] 'agreement among raft nodes before linearized reading'  (duration: 191.746997ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:12.217479Z","caller":"traceutil/trace.go:171","msg":"trace[2019710228] transaction","detail":"{read_only:false; response_revision:24557; number_of_response:1; }","duration":"153.043479ms","start":"2026-05-25T01:31:12.064409Z","end":"2026-05-25T01:31:12.217452Z","steps":["trace[2019710228] 'process raft request'  (duration: 132.535119ms)","trace[2019710228] 'compare'  (duration: 20.216124ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:12.217334Z","caller":"traceutil/trace.go:171","msg":"trace[616218887] linearizableReadLoop","detail":"{readStateIndex:25376; appliedIndex:25375; }","duration":"137.390184ms","start":"2026-05-25T01:31:12.079921Z","end":"2026-05-25T01:31:12.217311Z","steps":["trace[616218887] 'read index received'  (duration: 117.043757ms)","trace[616218887] 'applied index is now lower than readState.Index'  (duration: 20.345487ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:31:12.217579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.647279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"}
{"level":"info","ts":"2026-05-25T01:31:12.217623Z","caller":"traceutil/trace.go:171","msg":"trace[1153620385] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24557; }","duration":"137.737691ms","start":"2026-05-25T01:31:12.079875Z","end":"2026-05-25T01:31:12.217612Z","steps":["trace[1153620385] 'agreement among raft nodes before linearized reading'  (duration: 137.626879ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:12.518475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.48595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:31:12.518583Z","caller":"traceutil/trace.go:171","msg":"trace[1088911617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24558; }","duration":"140.633983ms","start":"2026-05-25T01:31:12.377925Z","end":"2026-05-25T01:31:12.518559Z","steps":["trace[1088911617] 'range keys from in-memory index tree'  (duration: 140.356027ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:12.681443Z","caller":"traceutil/trace.go:171","msg":"trace[1009266271] transaction","detail":"{read_only:false; response_revision:24559; number_of_response:1; }","duration":"112.697635ms","start":"2026-05-25T01:31:12.56872Z","end":"2026-05-25T01:31:12.681418Z","steps":["trace[1009266271] 'process raft request'  (duration: 112.337217ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:13.062204Z","caller":"traceutil/trace.go:171","msg":"trace[16926497] transaction","detail":"{read_only:false; response_revision:24560; number_of_response:1; }","duration":"156.977034ms","start":"2026-05-25T01:31:12.905207Z","end":"2026-05-25T01:31:13.062184Z","steps":["trace[16926497] 'process raft request'  (duration: 156.820351ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:13.072169Z","caller":"traceutil/trace.go:171","msg":"trace[985172346] transaction","detail":"{read_only:false; response_revision:24562; number_of_response:1; }","duration":"166.206272ms","start":"2026-05-25T01:31:12.905942Z","end":"2026-05-25T01:31:13.072148Z","steps":["trace[985172346] 'process raft request'  (duration: 166.101109ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:13.072208Z","caller":"traceutil/trace.go:171","msg":"trace[515067777] transaction","detail":"{read_only:false; response_revision:24561; number_of_response:1; }","duration":"166.339564ms","start":"2026-05-25T01:31:12.905846Z","end":"2026-05-25T01:31:13.072185Z","steps":["trace[515067777] 'process raft request'  (duration: 166.088739ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:13.072309Z","caller":"traceutil/trace.go:171","msg":"trace[1437199584] transaction","detail":"{read_only:false; response_revision:24563; number_of_response:1; }","duration":"166.096358ms","start":"2026-05-25T01:31:12.906175Z","end":"2026-05-25T01:31:13.072272Z","steps":["trace[1437199584] 'process raft request'  (duration: 165.906764ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:13.706688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.169771ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10836960723862540246 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:24552 > 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\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-25T01:31:13.706838Z","caller":"traceutil/trace.go:171","msg":"trace[1065786586] linearizableReadLoop","detail":"{readStateIndex:25385; appliedIndex:25383; }","duration":"337.041221ms","start":"2026-05-25T01:31:13.369777Z","end":"2026-05-25T01:31:13.706819Z","steps":["trace[1065786586] 'read index received'  (duration: 34.639442ms)","trace[1065786586] 'applied index is now lower than readState.Index'  (duration: 302.400229ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:13.706938Z","caller":"traceutil/trace.go:171","msg":"trace[1919706708] transaction","detail":"{read_only:false; response_revision:24566; number_of_response:1; }","duration":"398.579929ms","start":"2026-05-25T01:31:13.308345Z","end":"2026-05-25T01:31:13.706925Z","steps":["trace[1919706708] 'process raft request'  (duration: 295.082832ms)","trace[1919706708] 'compare'  (duration: 102.935725ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:31:13.707202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:13.308326Z","time spent":"398.816134ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:24552 > 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-05-25T01:31:13.707384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.458886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-05-25T01:31:13.707423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.316385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"warn","ts":"2026-05-25T01:31:13.70741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.064341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:31:13.707477Z","caller":"traceutil/trace.go:171","msg":"trace[1478464606] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:24566; }","duration":"216.417958ms","start":"2026-05-25T01:31:13.491048Z","end":"2026-05-25T01:31:13.707466Z","steps":["trace[1478464606] 'agreement among raft nodes before linearized reading'  (duration: 216.276545ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:13.707489Z","caller":"traceutil/trace.go:171","msg":"trace[408847374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24566; }","duration":"327.597488ms","start":"2026-05-25T01:31:13.379865Z","end":"2026-05-25T01:31:13.707462Z","steps":["trace[408847374] 'agreement among raft nodes before linearized reading'  (duration: 327.390903ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:13.707509Z","caller":"traceutil/trace.go:171","msg":"trace[1059706670] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:24566; }","duration":"189.216134ms","start":"2026-05-25T01:31:13.518271Z","end":"2026-05-25T01:31:13.707487Z","steps":["trace[1059706670] 'agreement among raft nodes before linearized reading'  (duration: 189.0115ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:13.707515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.717646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"warn","ts":"2026-05-25T01:31:13.707566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:13.379831Z","time spent":"327.712141ms","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-05-25T01:31:13.707596Z","caller":"traceutil/trace.go:171","msg":"trace[1314912216] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:24566; }","duration":"337.855679ms","start":"2026-05-25T01:31:13.369722Z","end":"2026-05-25T01:31:13.707578Z","steps":["trace[1314912216] 'agreement among raft nodes before linearized reading'  (duration: 337.631114ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:13.707647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:13.369702Z","time spent":"337.935381ms","remote":"127.0.0.1:45052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":90,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "}
{"level":"info","ts":"2026-05-25T01:31:14.292416Z","caller":"traceutil/trace.go:171","msg":"trace[780494154] transaction","detail":"{read_only:false; response_revision:24570; number_of_response:1; }","duration":"227.667557ms","start":"2026-05-25T01:31:14.064712Z","end":"2026-05-25T01:31:14.29238Z","steps":["trace[780494154] 'process raft request'  (duration: 193.356892ms)","trace[780494154] 'compare'  (duration: 33.980458ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:14.292707Z","caller":"traceutil/trace.go:171","msg":"trace[805028709] transaction","detail":"{read_only:false; response_revision:24571; number_of_response:1; }","duration":"227.459984ms","start":"2026-05-25T01:31:14.065216Z","end":"2026-05-25T01:31:14.292676Z","steps":["trace[805028709] 'process raft request'  (duration: 227.058785ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:14.604813Z","caller":"traceutil/trace.go:171","msg":"trace[1609999597] transaction","detail":"{read_only:false; response_revision:24572; number_of_response:1; }","duration":"307.807335ms","start":"2026-05-25T01:31:14.296971Z","end":"2026-05-25T01:31:14.604779Z","steps":["trace[1609999597] 'process raft request'  (duration: 292.117528ms)","trace[1609999597] 'compare'  (duration: 15.520444ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:31:14.605078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:14.296948Z","time spent":"308.039789ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:24557 > 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-05-25T01:31:14.606697Z","caller":"traceutil/trace.go:171","msg":"trace[1049543867] linearizableReadLoop","detail":"{readStateIndex:25393; appliedIndex:25391; }","duration":"226.471443ms","start":"2026-05-25T01:31:14.380194Z","end":"2026-05-25T01:31:14.606665Z","steps":["trace[1049543867] 'read index received'  (duration: 208.894215ms)","trace[1049543867] 'applied index is now lower than readState.Index'  (duration: 17.575378ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:31:14.607067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.9366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-05-25T01:31:14.607054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.84111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:31:14.607073Z","caller":"traceutil/trace.go:171","msg":"trace[1642290355] transaction","detail":"{read_only:false; response_revision:24573; number_of_response:1; }","duration":"309.121293ms","start":"2026-05-25T01:31:14.297854Z","end":"2026-05-25T01:31:14.606975Z","steps":["trace[1642290355] 'process raft request'  (duration: 308.627212ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:14.607121Z","caller":"traceutil/trace.go:171","msg":"trace[842745883] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:24573; }","duration":"120.040632ms","start":"2026-05-25T01:31:14.487066Z","end":"2026-05-25T01:31:14.607107Z","steps":["trace[842745883] 'agreement among raft nodes before linearized reading'  (duration: 119.94106ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:14.607139Z","caller":"traceutil/trace.go:171","msg":"trace[167347627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24573; }","duration":"226.982083ms","start":"2026-05-25T01:31:14.380139Z","end":"2026-05-25T01:31:14.607121Z","steps":["trace[167347627] 'agreement among raft nodes before linearized reading'  (duration: 226.783979ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:14.607243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:14.297829Z","time spent":"309.323158ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:24558 > 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":"warn","ts":"2026-05-25T01:31:14.86297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.606124ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10836960723862540270 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:24559 > success:<request_put:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" value_size:441 >> failure:<request_range:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-25T01:31:14.863052Z","caller":"traceutil/trace.go:171","msg":"trace[1077838425] linearizableReadLoop","detail":"{readStateIndex:25394; appliedIndex:25393; }","duration":"122.376512ms","start":"2026-05-25T01:31:14.740659Z","end":"2026-05-25T01:31:14.863035Z","steps":["trace[1077838425] 'read index received'  (duration: 54.011µs)","trace[1077838425] 'applied index is now lower than readState.Index'  (duration: 122.321441ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:31:14.863135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.475135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:31:14.863156Z","caller":"traceutil/trace.go:171","msg":"trace[116430559] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:24574; }","duration":"122.546616ms","start":"2026-05-25T01:31:14.740602Z","end":"2026-05-25T01:31:14.863149Z","steps":["trace[116430559] 'agreement among raft nodes before linearized reading'  (duration: 122.468415ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:14.863354Z","caller":"traceutil/trace.go:171","msg":"trace[130017319] transaction","detail":"{read_only:false; response_revision:24574; number_of_response:1; }","duration":"177.297328ms","start":"2026-05-25T01:31:14.686041Z","end":"2026-05-25T01:31:14.863338Z","steps":["trace[130017319] 'process raft request'  (duration: 49.252875ms)","trace[130017319] 'compare'  (duration: 127.372239ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:15.004892Z","caller":"traceutil/trace.go:171","msg":"trace[97018792] transaction","detail":"{read_only:false; response_revision:24575; number_of_response:1; }","duration":"156.419842ms","start":"2026-05-25T01:31:14.848428Z","end":"2026-05-25T01:31:15.004848Z","steps":["trace[97018792] 'process raft request'  (duration: 119.780817ms)","trace[97018792] 'compare'  (duration: 36.246966ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:15.441072Z","caller":"traceutil/trace.go:171","msg":"trace[1113238505] transaction","detail":"{read_only:false; response_revision:24577; number_of_response:1; }","duration":"363.444146ms","start":"2026-05-25T01:31:15.077577Z","end":"2026-05-25T01:31:15.441021Z","steps":["trace[1113238505] 'process raft request'  (duration: 296.017541ms)","trace[1113238505] 'compare'  (duration: 67.099228ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:15.4411Z","caller":"traceutil/trace.go:171","msg":"trace[911465992] linearizableReadLoop","detail":"{readStateIndex:25399; appliedIndex:25396; }","duration":"132.700813ms","start":"2026-05-25T01:31:15.308368Z","end":"2026-05-25T01:31:15.441069Z","steps":["trace[911465992] 'read index received'  (duration: 65.143646ms)","trace[911465992] 'applied index is now lower than readState.Index'  (duration: 67.555867ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:15.441132Z","caller":"traceutil/trace.go:171","msg":"trace[1685109764] transaction","detail":"{read_only:false; response_revision:24578; number_of_response:1; }","duration":"363.515868ms","start":"2026-05-25T01:31:15.077584Z","end":"2026-05-25T01:31:15.4411Z","steps":["trace[1685109764] 'process raft request'  (duration: 363.338044ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:15.4412Z","caller":"traceutil/trace.go:171","msg":"trace[1138833067] transaction","detail":"{read_only:false; response_revision:24579; number_of_response:1; }","duration":"363.265182ms","start":"2026-05-25T01:31:15.077913Z","end":"2026-05-25T01:31:15.441178Z","steps":["trace[1138833067] 'process raft request'  (duration: 363.089849ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:15.441297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:15.077554Z","time spent":"363.61935ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" mod_revision:24563 > success:<request_put:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" value_size:506 >> failure:<request_range:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" > >"}
{"level":"warn","ts":"2026-05-25T01:31:15.441313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.937228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"}
{"level":"info","ts":"2026-05-25T01:31:15.441348Z","caller":"traceutil/trace.go:171","msg":"trace[1494643271] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24579; }","duration":"133.009571ms","start":"2026-05-25T01:31:15.308327Z","end":"2026-05-25T01:31:15.441337Z","steps":["trace[1494643271] 'agreement among raft nodes before linearized reading'  (duration: 132.860666ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:15.441333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:15.077902Z","time spent":"363.346864ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:24562 > 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-05-25T01:31:15.441383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:15.077557Z","time spent":"363.669771ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:24561 > 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-05-25T01:31:15.633151Z","caller":"traceutil/trace.go:171","msg":"trace[135920237] transaction","detail":"{read_only:false; response_revision:24581; number_of_response:1; }","duration":"185.887392ms","start":"2026-05-25T01:31:15.447237Z","end":"2026-05-25T01:31:15.633124Z","steps":["trace[135920237] 'process raft request'  (duration: 147.669603ms)","trace[135920237] 'compare'  (duration: 38.101997ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:16.246848Z","caller":"traceutil/trace.go:171","msg":"trace[1699687008] transaction","detail":"{read_only:false; response_revision:24583; number_of_response:1; }","duration":"274.700015ms","start":"2026-05-25T01:31:15.972126Z","end":"2026-05-25T01:31:16.246826Z","steps":["trace[1699687008] 'process raft request'  (duration: 274.531572ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:16.41671Z","caller":"traceutil/trace.go:171","msg":"trace[7730822] transaction","detail":"{read_only:false; response_revision:24584; number_of_response:1; }","duration":"118.977459ms","start":"2026-05-25T01:31:16.297712Z","end":"2026-05-25T01:31:16.41669Z","steps":["trace[7730822] 'process raft request'  (duration: 118.841126ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:16.705668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.391111ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10836960723862540287 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:24570 > 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\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-25T01:31:16.705739Z","caller":"traceutil/trace.go:171","msg":"trace[696684245] linearizableReadLoop","detail":"{readStateIndex:25405; appliedIndex:25403; }","duration":"359.173205ms","start":"2026-05-25T01:31:16.346554Z","end":"2026-05-25T01:31:16.705727Z","steps":["trace[696684245] 'read index received'  (duration: 70.03102ms)","trace[696684245] 'applied index is now lower than readState.Index'  (duration: 289.141415ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:16.705801Z","caller":"traceutil/trace.go:171","msg":"trace[480067312] transaction","detail":"{read_only:false; response_revision:24585; number_of_response:1; }","duration":"407.052961ms","start":"2026-05-25T01:31:16.298738Z","end":"2026-05-25T01:31:16.705791Z","steps":["trace[480067312] 'process raft request'  (duration: 264.463166ms)","trace[480067312] 'compare'  (duration: 142.114775ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:31:16.70584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:16.298714Z","time spent":"407.104902ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:24570 > 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":"warn","ts":"2026-05-25T01:31:16.706013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.350022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-05-25T01:31:16.706107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.551683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-05-25T01:31:16.706105Z","caller":"traceutil/trace.go:171","msg":"trace[332685986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24585; }","duration":"326.487155ms","start":"2026-05-25T01:31:16.379593Z","end":"2026-05-25T01:31:16.70608Z","steps":["trace[332685986] 'agreement among raft nodes before linearized reading'  (duration: 326.338952ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:16.706157Z","caller":"traceutil/trace.go:171","msg":"trace[1736575511] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:24585; }","duration":"359.640475ms","start":"2026-05-25T01:31:16.346504Z","end":"2026-05-25T01:31:16.706144Z","steps":["trace[1736575511] 'agreement among raft nodes before linearized reading'  (duration: 359.546833ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:16.706175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:16.37956Z","time spent":"326.600398ms","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-05-25T01:31:16.706189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:16.346481Z","time spent":"359.698436ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":23,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true "}
{"level":"info","ts":"2026-05-25T01:31:16.898227Z","caller":"traceutil/trace.go:171","msg":"trace[95141247] transaction","detail":"{read_only:false; response_revision:24587; number_of_response:1; }","duration":"187.917117ms","start":"2026-05-25T01:31:16.710282Z","end":"2026-05-25T01:31:16.898199Z","steps":["trace[95141247] 'process raft request'  (duration: 168.759276ms)","trace[95141247] 'compare'  (duration: 18.903304ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:17.078054Z","caller":"traceutil/trace.go:171","msg":"trace[1629409969] transaction","detail":"{read_only:false; response_revision:24588; number_of_response:1; }","duration":"210.362377ms","start":"2026-05-25T01:31:16.867661Z","end":"2026-05-25T01:31:17.078023Z","steps":["trace[1629409969] 'process raft request'  (duration: 177.428371ms)","trace[1629409969] 'compare'  (duration: 32.785623ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:17.311769Z","caller":"traceutil/trace.go:171","msg":"trace[927429530] transaction","detail":"{read_only:false; response_revision:24589; number_of_response:1; }","duration":"160.432667ms","start":"2026-05-25T01:31:17.151297Z","end":"2026-05-25T01:31:17.31173Z","steps":["trace[927429530] 'process raft request'  (duration: 143.387082ms)","trace[927429530] 'compare'  (duration: 16.835531ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:18.447583Z","caller":"traceutil/trace.go:171","msg":"trace[1245636105] transaction","detail":"{read_only:false; response_revision:24596; number_of_response:1; }","duration":"192.162438ms","start":"2026-05-25T01:31:18.25537Z","end":"2026-05-25T01:31:18.447532Z","steps":["trace[1245636105] 'process raft request'  (duration: 191.812009ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:18.666429Z","caller":"traceutil/trace.go:171","msg":"trace[1924707872] linearizableReadLoop","detail":"{readStateIndex:25417; appliedIndex:25416; }","duration":"217.071951ms","start":"2026-05-25T01:31:18.449333Z","end":"2026-05-25T01:31:18.666405Z","steps":["trace[1924707872] 'read index received'  (duration: 168.888159ms)","trace[1924707872] 'applied index is now lower than readState.Index'  (duration: 48.182392ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:18.666531Z","caller":"traceutil/trace.go:171","msg":"trace[342988948] transaction","detail":"{read_only:false; response_revision:24597; number_of_response:1; }","duration":"244.974039ms","start":"2026-05-25T01:31:18.421516Z","end":"2026-05-25T01:31:18.66649Z","steps":["trace[342988948] 'process raft request'  (duration: 196.801327ms)","trace[342988948] 'compare'  (duration: 47.824835ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:31:18.666581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.238215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:31:18.666832Z","caller":"traceutil/trace.go:171","msg":"trace[484287786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24597; }","duration":"217.538161ms","start":"2026-05-25T01:31:18.449283Z","end":"2026-05-25T01:31:18.666821Z","steps":["trace[484287786] 'agreement among raft nodes before linearized reading'  (duration: 217.210064ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:19.960628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.831274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"}
{"level":"info","ts":"2026-05-25T01:31:19.960735Z","caller":"traceutil/trace.go:171","msg":"trace[1058701300] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24611; }","duration":"173.008368ms","start":"2026-05-25T01:31:19.787702Z","end":"2026-05-25T01:31:19.960711Z","steps":["trace[1058701300] 'range keys from in-memory index tree'  (duration: 172.645249ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:20.187213Z","caller":"traceutil/trace.go:171","msg":"trace[1295274292] transaction","detail":"{read_only:false; response_revision:24612; number_of_response:1; }","duration":"222.071959ms","start":"2026-05-25T01:31:19.965116Z","end":"2026-05-25T01:31:20.187188Z","steps":["trace[1295274292] 'process raft request'  (duration: 221.929056ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:32.899191Z","caller":"traceutil/trace.go:171","msg":"trace[2019180708] linearizableReadLoop","detail":"{readStateIndex:25523; appliedIndex:25522; }","duration":"132.511149ms","start":"2026-05-25T01:31:32.766656Z","end":"2026-05-25T01:31:32.899167Z","steps":["trace[2019180708] 'read index received'  (duration: 132.395676ms)","trace[2019180708] 'applied index is now lower than readState.Index'  (duration: 114.573µs)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:32.899291Z","caller":"traceutil/trace.go:171","msg":"trace[1850342691] transaction","detail":"{read_only:false; response_revision:24700; number_of_response:1; }","duration":"143.668598ms","start":"2026-05-25T01:31:32.755599Z","end":"2026-05-25T01:31:32.899268Z","steps":["trace[1850342691] 'process raft request'  (duration: 143.420443ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:32.899331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.663612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:31:32.899366Z","caller":"traceutil/trace.go:171","msg":"trace[932423379] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24700; }","duration":"132.711503ms","start":"2026-05-25T01:31:32.766642Z","end":"2026-05-25T01:31:32.899354Z","steps":["trace[932423379] 'agreement among raft nodes before linearized reading'  (duration: 132.638471ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:32.901156Z","caller":"traceutil/trace.go:171","msg":"trace[1535227092] transaction","detail":"{read_only:false; response_revision:24701; number_of_response:1; }","duration":"102.748271ms","start":"2026-05-25T01:31:32.798391Z","end":"2026-05-25T01:31:32.901139Z","steps":["trace[1535227092] 'process raft request'  (duration: 102.347373ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:35.858166Z","caller":"traceutil/trace.go:171","msg":"trace[1586525684] transaction","detail":"{read_only:false; response_revision:24782; number_of_response:1; }","duration":"324.306808ms","start":"2026-05-25T01:31:35.533837Z","end":"2026-05-25T01:31:35.858144Z","steps":["trace[1586525684] 'process raft request'  (duration: 324.165545ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:35.858303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:35.533815Z","time spent":"324.413361ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" mod_revision:24706 > 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-05-25T01:31:35.908244Z","caller":"traceutil/trace.go:171","msg":"trace[814376457] linearizableReadLoop","detail":"{readStateIndex:25608; appliedIndex:25605; }","duration":"327.521167ms","start":"2026-05-25T01:31:35.580696Z","end":"2026-05-25T01:31:35.908218Z","steps":["trace[814376457] 'read index received'  (duration: 277.302411ms)","trace[814376457] 'applied index is now lower than readState.Index'  (duration: 50.217606ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:35.908275Z","caller":"traceutil/trace.go:171","msg":"trace[1815673571] transaction","detail":"{read_only:false; response_revision:24783; number_of_response:1; }","duration":"343.03818ms","start":"2026-05-25T01:31:35.565211Z","end":"2026-05-25T01:31:35.908249Z","steps":["trace[1815673571] 'process raft request'  (duration: 339.922832ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:35.908325Z","caller":"traceutil/trace.go:171","msg":"trace[70231843] transaction","detail":"{read_only:false; response_revision:24784; number_of_response:1; }","duration":"338.803239ms","start":"2026-05-25T01:31:35.569486Z","end":"2026-05-25T01:31:35.908289Z","steps":["trace[70231843] 'process raft request'  (duration: 338.671826ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:35.908436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:35.565188Z","time spent":"343.153543ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:24707 > 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-05-25T01:31:35.908496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:35.569474Z","time spent":"338.923401ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:24708 > 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-05-25T01:31:35.908512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.009471ms","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-05-25T01:31:35.908574Z","caller":"traceutil/trace.go:171","msg":"trace[575293869] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24784; }","duration":"232.121383ms","start":"2026-05-25T01:31:35.676436Z","end":"2026-05-25T01:31:35.908558Z","steps":["trace[575293869] 'agreement among raft nodes before linearized reading'  (duration: 231.95021ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:35.908576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.811014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"}
{"level":"info","ts":"2026-05-25T01:31:35.908648Z","caller":"traceutil/trace.go:171","msg":"trace[2083624788] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24784; }","duration":"117.913667ms","start":"2026-05-25T01:31:35.790711Z","end":"2026-05-25T01:31:35.908624Z","steps":["trace[2083624788] 'agreement among raft nodes before linearized reading'  (duration: 117.727753ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:35.909015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.302863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d9f20765-549d-4731-a125-2a291d7c7e4f\" ","response":"range_response_count:1 size:3644"}
{"level":"info","ts":"2026-05-25T01:31:35.909087Z","caller":"traceutil/trace.go:171","msg":"trace[1956273618] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d9f20765-549d-4731-a125-2a291d7c7e4f; range_end:; response_count:1; response_revision:24784; }","duration":"328.412936ms","start":"2026-05-25T01:31:35.580654Z","end":"2026-05-25T01:31:35.909067Z","steps":["trace[1956273618] 'agreement among raft nodes before linearized reading'  (duration: 327.65867ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:35.909117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:35.580643Z","time spent":"328.467217ms","remote":"127.0.0.1:45064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d9f20765-549d-4731-a125-2a291d7c7e4f\" "}
{"level":"info","ts":"2026-05-25T01:31:36.085623Z","caller":"traceutil/trace.go:171","msg":"trace[58191332] transaction","detail":"{read_only:false; response_revision:24788; number_of_response:1; }","duration":"168.095451ms","start":"2026-05-25T01:31:35.917502Z","end":"2026-05-25T01:31:36.085597Z","steps":["trace[58191332] 'process raft request'  (duration: 168.03999ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:36.085715Z","caller":"traceutil/trace.go:171","msg":"trace[842246718] transaction","detail":"{read_only:false; response_revision:24786; number_of_response:1; }","duration":"171.738249ms","start":"2026-05-25T01:31:35.913941Z","end":"2026-05-25T01:31:36.08568Z","steps":["trace[842246718] 'process raft request'  (duration: 170.758258ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:36.085871Z","caller":"traceutil/trace.go:171","msg":"trace[436637565] transaction","detail":"{read_only:false; response_revision:24787; number_of_response:1; }","duration":"171.812351ms","start":"2026-05-25T01:31:35.914022Z","end":"2026-05-25T01:31:36.085834Z","steps":["trace[436637565] 'process raft request'  (duration: 171.468244ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:36.366313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.416176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:31:36.366408Z","caller":"traceutil/trace.go:171","msg":"trace[1210138875] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:24788; }","duration":"145.558289ms","start":"2026-05-25T01:31:36.22082Z","end":"2026-05-25T01:31:36.366379Z","steps":["trace[1210138875] 'count revisions from in-memory index tree'  (duration: 145.319113ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:36.548464Z","caller":"traceutil/trace.go:171","msg":"trace[1113056639] linearizableReadLoop","detail":"{readStateIndex:25613; appliedIndex:25612; }","duration":"165.843832ms","start":"2026-05-25T01:31:36.382599Z","end":"2026-05-25T01:31:36.548443Z","steps":["trace[1113056639] 'read index received'  (duration: 165.633328ms)","trace[1113056639] 'applied index is now lower than readState.Index'  (duration: 210.024µs)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:31:36.548585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.968716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:31:36.548567Z","caller":"traceutil/trace.go:171","msg":"trace[1612181082] transaction","detail":"{read_only:false; response_revision:24789; number_of_response:1; }","duration":"176.254266ms","start":"2026-05-25T01:31:36.372272Z","end":"2026-05-25T01:31:36.548527Z","steps":["trace[1612181082] 'process raft request'  (duration: 176.016341ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:36.548613Z","caller":"traceutil/trace.go:171","msg":"trace[683239561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24789; }","duration":"166.025887ms","start":"2026-05-25T01:31:36.382579Z","end":"2026-05-25T01:31:36.548605Z","steps":["trace[683239561] 'agreement among raft nodes before linearized reading'  (duration: 165.964776ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:36.729748Z","caller":"traceutil/trace.go:171","msg":"trace[429573977] transaction","detail":"{read_only:false; response_revision:24790; number_of_response:1; }","duration":"176.998252ms","start":"2026-05-25T01:31:36.552707Z","end":"2026-05-25T01:31:36.729705Z","steps":["trace[429573977] 'process raft request'  (duration: 164.698368ms)","trace[429573977] 'compare'  (duration: 12.131761ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:37.047214Z","caller":"traceutil/trace.go:171","msg":"trace[1046463580] transaction","detail":"{read_only:false; response_revision:24791; number_of_response:1; }","duration":"129.703609ms","start":"2026-05-25T01:31:36.917484Z","end":"2026-05-25T01:31:37.047188Z","steps":["trace[1046463580] 'process raft request'  (duration: 129.513485ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:37.048502Z","caller":"traceutil/trace.go:171","msg":"trace[2073044969] transaction","detail":"{read_only:false; response_revision:24792; number_of_response:1; }","duration":"130.68664ms","start":"2026-05-25T01:31:36.917785Z","end":"2026-05-25T01:31:37.048471Z","steps":["trace[2073044969] 'process raft request'  (duration: 130.434184ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:37.350183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.692419ms","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-05-25T01:31:37.350289Z","caller":"traceutil/trace.go:171","msg":"trace[1548304690] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:24793; }","duration":"270.827032ms","start":"2026-05-25T01:31:37.079434Z","end":"2026-05-25T01:31:37.350261Z","steps":["trace[1548304690] 'range keys from in-memory index tree'  (duration: 270.527245ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:37.350376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.406594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"}
{"level":"info","ts":"2026-05-25T01:31:37.35051Z","caller":"traceutil/trace.go:171","msg":"trace[723822492] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24793; }","duration":"277.576147ms","start":"2026-05-25T01:31:37.07291Z","end":"2026-05-25T01:31:37.350486Z","steps":["trace[723822492] 'range keys from in-memory index tree'  (duration: 277.153658ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:37.350331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.881073ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:31:37.351093Z","caller":"traceutil/trace.go:171","msg":"trace[1029082154] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24793; }","duration":"179.63322ms","start":"2026-05-25T01:31:37.171418Z","end":"2026-05-25T01:31:37.351051Z","steps":["trace[1029082154] 'range keys from in-memory index tree'  (duration: 178.862113ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:37.353718Z","caller":"traceutil/trace.go:171","msg":"trace[1394333056] transaction","detail":"{read_only:false; response_revision:24794; number_of_response:1; }","duration":"166.286463ms","start":"2026-05-25T01:31:37.187414Z","end":"2026-05-25T01:31:37.3537Z","steps":["trace[1394333056] 'process raft request'  (duration: 166.064868ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:37.784772Z","caller":"traceutil/trace.go:171","msg":"trace[868878006] transaction","detail":"{read_only:false; response_revision:24796; number_of_response:1; }","duration":"382.654158ms","start":"2026-05-25T01:31:37.402096Z","end":"2026-05-25T01:31:37.78475Z","steps":["trace[868878006] 'process raft request'  (duration: 382.569176ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:37.784815Z","caller":"traceutil/trace.go:171","msg":"trace[1742525009] transaction","detail":"{read_only:false; response_revision:24795; number_of_response:1; }","duration":"430.39063ms","start":"2026-05-25T01:31:37.354383Z","end":"2026-05-25T01:31:37.784773Z","steps":["trace[1742525009] 'process raft request'  (duration: 420.981479ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:37.784928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:37.402072Z","time spent":"382.76978ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:24780 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:459 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"warn","ts":"2026-05-25T01:31:37.784975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:37.354337Z","time spent":"430.554104ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:24774 > 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-05-25T01:31:38.408648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.959792ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10836960723862540821 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:24782 > 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-05-25T01:31:38.408843Z","caller":"traceutil/trace.go:171","msg":"trace[1084470646] transaction","detail":"{read_only:false; response_revision:24797; number_of_response:1; }","duration":"545.59201ms","start":"2026-05-25T01:31:37.863218Z","end":"2026-05-25T01:31:38.40881Z","steps":["trace[1084470646] 'process raft request'  (duration: 241.360672ms)","trace[1084470646] 'compare'  (duration: 303.637805ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:38.408914Z","caller":"traceutil/trace.go:171","msg":"trace[1694032848] linearizableReadLoop","detail":"{readStateIndex:25623; appliedIndex:25620; }","duration":"492.822668ms","start":"2026-05-25T01:31:37.916074Z","end":"2026-05-25T01:31:38.408897Z","steps":["trace[1694032848] 'read index received'  (duration: 188.481188ms)","trace[1694032848] 'applied index is now lower than readState.Index'  (duration: 304.34024ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:31:38.408953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:37.863201Z","time spent":"545.695331ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" mod_revision:24782 > 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-05-25T01:31:38.409137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.060714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d9f20765-549d-4731-a125-2a291d7c7e4f\" ","response":"range_response_count:1 size:3761"}
{"level":"info","ts":"2026-05-25T01:31:38.409161Z","caller":"traceutil/trace.go:171","msg":"trace[997580250] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d9f20765-549d-4731-a125-2a291d7c7e4f; range_end:; response_count:1; response_revision:24799; }","duration":"493.084305ms","start":"2026-05-25T01:31:37.916069Z","end":"2026-05-25T01:31:38.409154Z","steps":["trace[997580250] 'agreement among raft nodes before linearized reading'  (duration: 492.998033ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:38.409176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:37.91604Z","time spent":"493.132175ms","remote":"127.0.0.1:45064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3784,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d9f20765-549d-4731-a125-2a291d7c7e4f\" "}
{"level":"info","ts":"2026-05-25T01:31:38.409259Z","caller":"traceutil/trace.go:171","msg":"trace[1853921418] transaction","detail":"{read_only:false; response_revision:24798; number_of_response:1; }","duration":"495.373314ms","start":"2026-05-25T01:31:37.91388Z","end":"2026-05-25T01:31:38.409253Z","steps":["trace[1853921418] 'process raft request'  (duration: 494.887854ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:38.409276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.144629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"}
{"level":"warn","ts":"2026-05-25T01:31:38.409297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:37.913855Z","time spent":"495.415574ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:24784 > 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":"info","ts":"2026-05-25T01:31:38.409315Z","caller":"traceutil/trace.go:171","msg":"trace[1013010670] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24799; }","duration":"489.19682ms","start":"2026-05-25T01:31:37.920106Z","end":"2026-05-25T01:31:38.409303Z","steps":["trace[1013010670] 'agreement among raft nodes before linearized reading'  (duration: 489.059567ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:38.409366Z","caller":"traceutil/trace.go:171","msg":"trace[1148816756] transaction","detail":"{read_only:false; response_revision:24799; number_of_response:1; }","duration":"494.093476ms","start":"2026-05-25T01:31:37.915265Z","end":"2026-05-25T01:31:38.409359Z","steps":["trace[1148816756] 'process raft request'  (duration: 493.596146ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:38.409417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:37.91525Z","time spent":"494.141947ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:24783 > 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-05-25T01:31:38.409365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:37.920099Z","time spent":"489.257592ms","remote":"127.0.0.1:45064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "}
{"level":"warn","ts":"2026-05-25T01:31:38.409712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.634475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"warn","ts":"2026-05-25T01:31:38.409737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.742808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-05-25T01:31:38.409752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.556056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"}
{"level":"info","ts":"2026-05-25T01:31:38.409761Z","caller":"traceutil/trace.go:171","msg":"trace[1223819897] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:24799; }","duration":"163.716117ms","start":"2026-05-25T01:31:38.246033Z","end":"2026-05-25T01:31:38.409749Z","steps":["trace[1223819897] 'agreement among raft nodes before linearized reading'  (duration: 163.645176ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:38.409762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.895131ms","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-05-25T01:31:38.409791Z","caller":"traceutil/trace.go:171","msg":"trace[736833021] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24799; }","duration":"319.599358ms","start":"2026-05-25T01:31:38.09018Z","end":"2026-05-25T01:31:38.40978Z","steps":["trace[736833021] 'agreement among raft nodes before linearized reading'  (duration: 319.381962ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:38.409799Z","caller":"traceutil/trace.go:171","msg":"trace[712107568] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:24799; }","duration":"261.849861ms","start":"2026-05-25T01:31:38.147924Z","end":"2026-05-25T01:31:38.409774Z","steps":["trace[712107568] 'agreement among raft nodes before linearized reading'  (duration: 261.741708ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:38.409815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:38.090102Z","time spent":"319.705741ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"info","ts":"2026-05-25T01:31:38.409812Z","caller":"traceutil/trace.go:171","msg":"trace[949282139] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24799; }","duration":"319.078546ms","start":"2026-05-25T01:31:38.09072Z","end":"2026-05-25T01:31:38.409798Z","steps":["trace[949282139] 'agreement among raft nodes before linearized reading'  (duration: 318.955753ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:38.409847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:38.090657Z","time spent":"319.183198ms","remote":"127.0.0.1:45152","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-05-25T01:31:40.685454Z","caller":"traceutil/trace.go:171","msg":"trace[272126534] linearizableReadLoop","detail":"{readStateIndex:25642; appliedIndex:25640; }","duration":"124.012747ms","start":"2026-05-25T01:31:40.561412Z","end":"2026-05-25T01:31:40.685425Z","steps":["trace[272126534] 'read index received'  (duration: 123.20467ms)","trace[272126534] 'applied index is now lower than readState.Index'  (duration: 807.087µs)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:40.685495Z","caller":"traceutil/trace.go:171","msg":"trace[753045340] transaction","detail":"{read_only:false; response_revision:24816; number_of_response:1; }","duration":"206.591566ms","start":"2026-05-25T01:31:40.478877Z","end":"2026-05-25T01:31:40.685469Z","steps":["trace[753045340] 'process raft request'  (duration: 205.726938ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:40.685715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.291333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"}
{"level":"info","ts":"2026-05-25T01:31:40.685586Z","caller":"traceutil/trace.go:171","msg":"trace[1562954840] transaction","detail":"{read_only:false; response_revision:24817; number_of_response:1; }","duration":"206.256679ms","start":"2026-05-25T01:31:40.479279Z","end":"2026-05-25T01:31:40.685535Z","steps":["trace[1562954840] 'process raft request'  (duration: 206.034885ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:40.685771Z","caller":"traceutil/trace.go:171","msg":"trace[194736425] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24817; }","duration":"124.400295ms","start":"2026-05-25T01:31:40.561354Z","end":"2026-05-25T01:31:40.685754Z","steps":["trace[194736425] 'agreement among raft nodes before linearized reading'  (duration: 124.212731ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:42.805948Z","caller":"traceutil/trace.go:171","msg":"trace[1421861750] linearizableReadLoop","detail":"{readStateIndex:25674; appliedIndex:25672; }","duration":"110.539078ms","start":"2026-05-25T01:31:42.695382Z","end":"2026-05-25T01:31:42.805921Z","steps":["trace[1421861750] 'read index received'  (duration: 56.47637ms)","trace[1421861750] 'applied index is now lower than readState.Index'  (duration: 54.061368ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:42.806019Z","caller":"traceutil/trace.go:171","msg":"trace[764924403] transaction","detail":"{read_only:false; response_revision:24849; number_of_response:1; }","duration":"111.402187ms","start":"2026-05-25T01:31:42.694579Z","end":"2026-05-25T01:31:42.805981Z","steps":["trace[764924403] 'process raft request'  (duration: 111.197462ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:42.806193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.798504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"}
{"level":"info","ts":"2026-05-25T01:31:42.80625Z","caller":"traceutil/trace.go:171","msg":"trace[1288264377] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24849; }","duration":"110.886076ms","start":"2026-05-25T01:31:42.695349Z","end":"2026-05-25T01:31:42.806235Z","steps":["trace[1288264377] 'agreement among raft nodes before linearized reading'  (duration: 110.697962ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:43.06214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.867929ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10836960723862540933 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:24819 > 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\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-25T01:31:43.062302Z","caller":"traceutil/trace.go:171","msg":"trace[1059325629] linearizableReadLoop","detail":"{readStateIndex:25676; appliedIndex:25674; }","duration":"193.421133ms","start":"2026-05-25T01:31:42.868865Z","end":"2026-05-25T01:31:43.062286Z","steps":["trace[1059325629] 'read index received'  (duration: 30.529614ms)","trace[1059325629] 'applied index is now lower than readState.Index'  (duration: 162.890389ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:31:43.06238Z","caller":"traceutil/trace.go:171","msg":"trace[2134247217] transaction","detail":"{read_only:false; response_revision:24851; number_of_response:1; }","duration":"251.412295ms","start":"2026-05-25T01:31:42.810958Z","end":"2026-05-25T01:31:43.062371Z","steps":["trace[2134247217] 'process raft request'  (duration: 251.270702ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:31:43.062462Z","caller":"traceutil/trace.go:171","msg":"trace[1136649150] transaction","detail":"{read_only:false; response_revision:24850; number_of_response:1; }","duration":"251.549349ms","start":"2026-05-25T01:31:42.810885Z","end":"2026-05-25T01:31:43.062435Z","steps":["trace[1136649150] 'process raft request'  (duration: 88.294641ms)","trace[1136649150] 'compare'  (duration: 162.605024ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:31:43.062587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.721401ms","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:8"}
{"level":"info","ts":"2026-05-25T01:31:43.062623Z","caller":"traceutil/trace.go:171","msg":"trace[451186489] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:24851; }","duration":"193.760801ms","start":"2026-05-25T01:31:42.868851Z","end":"2026-05-25T01:31:43.062612Z","steps":["trace[451186489] 'agreement among raft nodes before linearized reading'  (duration: 193.69223ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:31:43.062688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.703439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:31:43.062788Z","caller":"traceutil/trace.go:171","msg":"trace[1402879922] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:24851; }","duration":"129.839351ms","start":"2026-05-25T01:31:42.932917Z","end":"2026-05-25T01:31:43.062756Z","steps":["trace[1402879922] 'agreement among raft nodes before linearized reading'  (duration: 129.722059ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:32:02.782085Z","caller":"traceutil/trace.go:171","msg":"trace[39954756] transaction","detail":"{read_only:false; response_revision:25013; number_of_response:1; }","duration":"248.064226ms","start":"2026-05-25T01:32:02.534001Z","end":"2026-05-25T01:32:02.782065Z","steps":["trace[39954756] 'process raft request'  (duration: 247.934973ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:32:03.004385Z","caller":"traceutil/trace.go:171","msg":"trace[1888764245] linearizableReadLoop","detail":"{readStateIndex:25845; appliedIndex:25842; }","duration":"404.536447ms","start":"2026-05-25T01:32:02.599826Z","end":"2026-05-25T01:32:03.004362Z","steps":["trace[1888764245] 'read index received'  (duration: 182.114482ms)","trace[1888764245] 'applied index is now lower than readState.Index'  (duration: 222.420325ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:32:03.004501Z","caller":"traceutil/trace.go:171","msg":"trace[1119566181] transaction","detail":"{read_only:false; response_revision:25015; number_of_response:1; }","duration":"455.97617ms","start":"2026-05-25T01:32:02.548515Z","end":"2026-05-25T01:32:03.004491Z","steps":["trace[1119566181] 'process raft request'  (duration: 455.752365ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:03.004609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:02.548509Z","time spent":"456.01835ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" mod_revision:24991 > 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-05-25T01:32:03.004633Z","caller":"traceutil/trace.go:171","msg":"trace[1353171479] transaction","detail":"{read_only:false; response_revision:25014; number_of_response:1; }","duration":"458.496644ms","start":"2026-05-25T01:32:02.546102Z","end":"2026-05-25T01:32:03.004599Z","steps":["trace[1353171479] 'process raft request'  (duration: 439.139689ms)","trace[1353171479] 'compare'  (duration: 18.757922ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:32:03.004733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.882724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:32:03.004816Z","caller":"traceutil/trace.go:171","msg":"trace[1343523179] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:25015; }","duration":"404.983986ms","start":"2026-05-25T01:32:02.599813Z","end":"2026-05-25T01:32:03.004797Z","steps":["trace[1343523179] 'agreement among raft nodes before linearized reading'  (duration: 404.834343ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:03.00487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:02.599794Z","time spent":"405.060367ms","remote":"127.0.0.1:41644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true "}
{"level":"warn","ts":"2026-05-25T01:32:03.00493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:02.546079Z","time spent":"458.622816ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:24992 > 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":"info","ts":"2026-05-25T01:32:03.239702Z","caller":"traceutil/trace.go:171","msg":"trace[675514406] linearizableReadLoop","detail":"{readStateIndex:25846; appliedIndex:25845; }","duration":"235.219649ms","start":"2026-05-25T01:32:03.004456Z","end":"2026-05-25T01:32:03.239676Z","steps":["trace[675514406] 'read index received'  (duration: 198.311439ms)","trace[675514406] 'applied index is now lower than readState.Index'  (duration: 36.90673ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:32:03.239749Z","caller":"traceutil/trace.go:171","msg":"trace[1438907225] transaction","detail":"{read_only:false; response_revision:25016; number_of_response:1; }","duration":"427.631362ms","start":"2026-05-25T01:32:02.812094Z","end":"2026-05-25T01:32:03.239725Z","steps":["trace[1438907225] 'process raft request'  (duration: 390.659391ms)","trace[1438907225] 'compare'  (duration: 36.550463ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:32:03.239845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.020325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-05-25T01:32:03.239853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:02.812069Z","time spent":"427.714474ms","remote":"127.0.0.1:45316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9527,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" mod_revision:25008 > success:<request_put:<key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" value_size:9464 >> failure:<request_range:<key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" > >"}
{"level":"info","ts":"2026-05-25T01:32:03.239891Z","caller":"traceutil/trace.go:171","msg":"trace[1893178364] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:25016; }","duration":"628.090347ms","start":"2026-05-25T01:32:02.611787Z","end":"2026-05-25T01:32:03.239877Z","steps":["trace[1893178364] 'agreement among raft nodes before linearized reading'  (duration: 628.007685ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:03.239889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.522791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"}
{"level":"warn","ts":"2026-05-25T01:32:03.239928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:02.611777Z","time spent":"628.141117ms","remote":"127.0.0.1:35752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true "}
{"level":"warn","ts":"2026-05-25T01:32:03.239915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.640983ms","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-05-25T01:32:03.239968Z","caller":"traceutil/trace.go:171","msg":"trace[1872461433] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25016; }","duration":"344.643054ms","start":"2026-05-25T01:32:02.895306Z","end":"2026-05-25T01:32:03.239949Z","steps":["trace[1872461433] 'agreement among raft nodes before linearized reading'  (duration: 344.392489ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:32:03.240001Z","caller":"traceutil/trace.go:171","msg":"trace[1647957501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25016; }","duration":"393.775886ms","start":"2026-05-25T01:32:02.846205Z","end":"2026-05-25T01:32:03.239981Z","steps":["trace[1647957501] 'agreement among raft nodes before linearized reading'  (duration: 393.548231ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:03.240029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:02.895283Z","time spent":"344.731636ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"warn","ts":"2026-05-25T01:32:03.240043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:02.846183Z","time spent":"393.852068ms","remote":"127.0.0.1:45152","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-05-25T01:32:03.52753Z","caller":"traceutil/trace.go:171","msg":"trace[1658249494] linearizableReadLoop","detail":"{readStateIndex:25848; appliedIndex:25847; }","duration":"287.738015ms","start":"2026-05-25T01:32:03.239766Z","end":"2026-05-25T01:32:03.527504Z","steps":["trace[1658249494] 'read index received'  (duration: 8.573274ms)","trace[1658249494] 'applied index is now lower than readState.Index'  (duration: 279.163451ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:32:03.527696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.098197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"}
{"level":"info","ts":"2026-05-25T01:32:03.527755Z","caller":"traceutil/trace.go:171","msg":"trace[2126041812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25019; }","duration":"298.194529ms","start":"2026-05-25T01:32:03.229548Z","end":"2026-05-25T01:32:03.527742Z","steps":["trace[2126041812] 'agreement among raft nodes before linearized reading'  (duration: 297.967745ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:32:03.527808Z","caller":"traceutil/trace.go:171","msg":"trace[1992836393] transaction","detail":"{read_only:false; response_revision:25019; number_of_response:1; }","duration":"284.349643ms","start":"2026-05-25T01:32:03.243447Z","end":"2026-05-25T01:32:03.527797Z","steps":["trace[1992836393] 'process raft request'  (duration: 283.994195ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:03.527808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.35685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-05-25T01:32:03.527879Z","caller":"traceutil/trace.go:171","msg":"trace[587356826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25019; }","duration":"367.481663ms","start":"2026-05-25T01:32:03.160375Z","end":"2026-05-25T01:32:03.527857Z","steps":["trace[587356826] 'agreement among raft nodes before linearized reading'  (duration: 367.280649ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:03.527943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:03.160303Z","time spent":"367.624376ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-05-25T01:32:03.527995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.068649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"}
{"level":"info","ts":"2026-05-25T01:32:03.528036Z","caller":"traceutil/trace.go:171","msg":"trace[1489670444] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:25019; }","duration":"384.133599ms","start":"2026-05-25T01:32:03.14389Z","end":"2026-05-25T01:32:03.528023Z","steps":["trace[1489670444] 'agreement among raft nodes before linearized reading'  (duration: 384.022807ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:32:03.527547Z","caller":"traceutil/trace.go:171","msg":"trace[264419956] transaction","detail":"{read_only:false; response_revision:25018; number_of_response:1; }","duration":"318.044595ms","start":"2026-05-25T01:32:03.209469Z","end":"2026-05-25T01:32:03.527513Z","steps":["trace[264419956] 'process raft request'  (duration: 264.990878ms)","trace[264419956] 'compare'  (duration: 52.823532ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:32:03.528148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.073489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"}
{"level":"info","ts":"2026-05-25T01:32:03.528181Z","caller":"traceutil/trace.go:171","msg":"trace[159729974] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25019; }","duration":"384.143451ms","start":"2026-05-25T01:32:03.14403Z","end":"2026-05-25T01:32:03.528173Z","steps":["trace[159729974] 'agreement among raft nodes before linearized reading'  (duration: 384.058829ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:03.528182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:03.209449Z","time spent":"318.656997ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/capi-system/controller-leader-election-capi\" mod_revision:24998 > success:<request_put:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" value_size:453 >> failure:<request_range:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" > >"}
{"level":"warn","ts":"2026-05-25T01:32:03.528211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:03.144008Z","time spent":"384.196491ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-05-25T01:32:03.52807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:03.143873Z","time spent":"384.188341ms","remote":"127.0.0.1:45042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1875,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" "}
{"level":"info","ts":"2026-05-25T01:32:03.705543Z","caller":"traceutil/trace.go:171","msg":"trace[167055496] linearizableReadLoop","detail":"{readStateIndex:25852; appliedIndex:25849; }","duration":"177.906001ms","start":"2026-05-25T01:32:03.527619Z","end":"2026-05-25T01:32:03.705525Z","steps":["trace[167055496] 'read index received'  (duration: 177.563064ms)","trace[167055496] 'applied index is now lower than readState.Index'  (duration: 342.147µs)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:32:03.705646Z","caller":"traceutil/trace.go:171","msg":"trace[1450988692] transaction","detail":"{read_only:false; response_revision:25021; number_of_response:1; }","duration":"456.845467ms","start":"2026-05-25T01:32:03.24879Z","end":"2026-05-25T01:32:03.705636Z","steps":["trace[1450988692] 'process raft request'  (duration: 456.648303ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:03.70575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:03.248769Z","time spent":"456.900669ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:24994 > 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-05-25T01:32:03.705914Z","caller":"traceutil/trace.go:171","msg":"trace[221404714] transaction","detail":"{read_only:false; response_revision:25020; number_of_response:1; }","duration":"457.274466ms","start":"2026-05-25T01:32:03.248611Z","end":"2026-05-25T01:32:03.705885Z","steps":["trace[221404714] 'process raft request'  (duration: 456.556911ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:32:03.705989Z","caller":"traceutil/trace.go:171","msg":"trace[697073197] transaction","detail":"{read_only:false; response_revision:25022; number_of_response:1; }","duration":"265.730752ms","start":"2026-05-25T01:32:03.440243Z","end":"2026-05-25T01:32:03.705974Z","steps":["trace[697073197] 'process raft request'  (duration: 265.245282ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:03.706068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:03.248595Z","time spent":"457.391529ms","remote":"127.0.0.1:36822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3218,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" mod_revision:25009 > success:<request_put:<key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" value_size:3145 >> failure:<request_range:<key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" > >"}
{"level":"warn","ts":"2026-05-25T01:32:03.706246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.176332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:32:03.706319Z","caller":"traceutil/trace.go:171","msg":"trace[322641137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25022; }","duration":"328.296704ms","start":"2026-05-25T01:32:03.378005Z","end":"2026-05-25T01:32:03.706302Z","steps":["trace[322641137] 'agreement among raft nodes before linearized reading'  (duration: 328.168512ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:03.706365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:03.377979Z","time spent":"328.377166ms","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-05-25T01:32:03.72631Z","caller":"traceutil/trace.go:171","msg":"trace[1096233903] transaction","detail":"{read_only:false; response_revision:25023; number_of_response:1; }","duration":"193.954916ms","start":"2026-05-25T01:32:03.532329Z","end":"2026-05-25T01:32:03.726284Z","steps":["trace[1096233903] 'process raft request'  (duration: 193.760852ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:32:03.726497Z","caller":"traceutil/trace.go:171","msg":"trace[1999308314] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"193.838033ms","start":"2026-05-25T01:32:03.532643Z","end":"2026-05-25T01:32:03.726481Z","steps":["trace[1999308314] 'process raft request'  (duration: 193.603508ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:03.72689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.029074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-05-25T01:32:03.726902Z","caller":"traceutil/trace.go:171","msg":"trace[580420859] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"193.821292ms","start":"2026-05-25T01:32:03.533059Z","end":"2026-05-25T01:32:03.726881Z","steps":["trace[580420859] 'process raft request'  (duration: 193.356782ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:32:03.726969Z","caller":"traceutil/trace.go:171","msg":"trace[280718081] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:25025; }","duration":"128.156256ms","start":"2026-05-25T01:32:03.598795Z","end":"2026-05-25T01:32:03.726951Z","steps":["trace[280718081] 'agreement among raft nodes before linearized reading'  (duration: 128.015393ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:03.727178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.944658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-6e5b54a6-800a-4145-9355-8da0935ff767\" ","response":"range_response_count:1 size:1318"}
{"level":"info","ts":"2026-05-25T01:32:03.727254Z","caller":"traceutil/trace.go:171","msg":"trace[855086715] range","detail":"{range_begin:/registry/persistentvolumes/pvc-6e5b54a6-800a-4145-9355-8da0935ff767; range_end:; response_count:1; response_revision:25025; }","duration":"196.04703ms","start":"2026-05-25T01:32:03.531187Z","end":"2026-05-25T01:32:03.727234Z","steps":["trace[855086715] 'agreement among raft nodes before linearized reading'  (duration: 195.859796ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:04.090629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.108471ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10836960723862541356 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/masterleases/199.204.45.246\" mod_revision:24944 > success:<request_put:<key:\"/registry/masterleases/199.204.45.246\" value_size:68 lease:1613588687007765545 >> failure:<request_range:<key:\"/registry/masterleases/199.204.45.246\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-25T01:32:04.090941Z","caller":"traceutil/trace.go:171","msg":"trace[2116906032] transaction","detail":"{read_only:false; response_revision:25027; number_of_response:1; }","duration":"218.611354ms","start":"2026-05-25T01:32:03.872305Z","end":"2026-05-25T01:32:04.090917Z","steps":["trace[2116906032] 'process raft request'  (duration: 218.44794ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:32:04.091033Z","caller":"traceutil/trace.go:171","msg":"trace[2030856709] linearizableReadLoop","detail":"{readStateIndex:25857; appliedIndex:25856; }","duration":"337.982321ms","start":"2026-05-25T01:32:03.753027Z","end":"2026-05-25T01:32:04.09101Z","steps":["trace[2030856709] 'read index received'  (duration: 121.373251ms)","trace[2030856709] 'applied index is now lower than readState.Index'  (duration: 216.60581ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:32:04.09115Z","caller":"traceutil/trace.go:171","msg":"trace[929709047] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"342.643361ms","start":"2026-05-25T01:32:03.74849Z","end":"2026-05-25T01:32:04.091134Z","steps":["trace[929709047] 'process raft request'  (duration: 125.897888ms)","trace[929709047] 'compare'  (duration: 215.65549ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:32:04.091254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:03.748477Z","time spent":"342.717932ms","remote":"127.0.0.1:44916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/masterleases/199.204.45.246\" mod_revision:24944 > success:<request_put:<key:\"/registry/masterleases/199.204.45.246\" value_size:68 lease:1613588687007765545 >> failure:<request_range:<key:\"/registry/masterleases/199.204.45.246\" > >"}
{"level":"warn","ts":"2026-05-25T01:32:04.091292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.252368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"}
{"level":"info","ts":"2026-05-25T01:32:04.091361Z","caller":"traceutil/trace.go:171","msg":"trace[2134898517] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25027; }","duration":"338.350569ms","start":"2026-05-25T01:32:03.752993Z","end":"2026-05-25T01:32:04.091343Z","steps":["trace[2134898517] 'agreement among raft nodes before linearized reading'  (duration: 338.144825ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:04.091407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:03.752982Z","time spent":"338.417161ms","remote":"127.0.0.1:45066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1281,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" "}
{"level":"warn","ts":"2026-05-25T01:32:04.148345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.555821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"}
{"level":"info","ts":"2026-05-25T01:32:04.148424Z","caller":"traceutil/trace.go:171","msg":"trace[442880158] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25028; }","duration":"147.665973ms","start":"2026-05-25T01:32:04.000739Z","end":"2026-05-25T01:32:04.148405Z","steps":["trace[442880158] 'agreement among raft nodes before linearized reading'  (duration: 147.482429ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:04.14843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.322683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"}
{"level":"info","ts":"2026-05-25T01:32:04.14853Z","caller":"traceutil/trace.go:171","msg":"trace[2057405124] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25028; }","duration":"265.432056ms","start":"2026-05-25T01:32:03.883075Z","end":"2026-05-25T01:32:04.148507Z","steps":["trace[2057405124] 'agreement among raft nodes before linearized reading'  (duration: 265.203671ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:04.148874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.662969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"}
{"level":"info","ts":"2026-05-25T01:32:04.148943Z","caller":"traceutil/trace.go:171","msg":"trace[523508813] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25028; }","duration":"228.761412ms","start":"2026-05-25T01:32:03.920162Z","end":"2026-05-25T01:32:04.148923Z","steps":["trace[523508813] 'agreement among raft nodes before linearized reading'  (duration: 228.608788ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:32:04.306292Z","caller":"traceutil/trace.go:171","msg":"trace[1759097125] linearizableReadLoop","detail":"{readStateIndex:25860; appliedIndex:25859; }","duration":"158.430385ms","start":"2026-05-25T01:32:04.14783Z","end":"2026-05-25T01:32:04.306261Z","steps":["trace[1759097125] 'read index received'  (duration: 66.665219ms)","trace[1759097125] 'applied index is now lower than readState.Index'  (duration: 91.763536ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:32:04.306452Z","caller":"traceutil/trace.go:171","msg":"trace[1547565998] transaction","detail":"{read_only:false; response_revision:25029; number_of_response:1; }","duration":"174.824036ms","start":"2026-05-25T01:32:04.131609Z","end":"2026-05-25T01:32:04.306433Z","steps":["trace[1547565998] 'process raft request'  (duration: 82.915146ms)","trace[1547565998] 'compare'  (duration: 91.508511ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:32:04.306568Z","caller":"traceutil/trace.go:171","msg":"trace[612203412] transaction","detail":"{read_only:false; response_revision:25031; number_of_response:1; }","duration":"152.791254ms","start":"2026-05-25T01:32:04.153758Z","end":"2026-05-25T01:32:04.306549Z","steps":["trace[612203412] 'process raft request'  (duration: 152.734693ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:32:04.306734Z","caller":"traceutil/trace.go:171","msg":"trace[418764920] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"153.373116ms","start":"2026-05-25T01:32:04.153353Z","end":"2026-05-25T01:32:04.306727Z","steps":["trace[418764920] 'process raft request'  (duration: 152.853665ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:04.306966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.351531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1444"}
{"level":"info","ts":"2026-05-25T01:32:04.307028Z","caller":"traceutil/trace.go:171","msg":"trace[2035603337] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25031; }","duration":"206.439593ms","start":"2026-05-25T01:32:04.100574Z","end":"2026-05-25T01:32:04.307014Z","steps":["trace[2035603337] 'agreement among raft nodes before linearized reading'  (duration: 206.220398ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:04.307159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.50341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18b2aae2f9565b26\" ","response":"range_response_count:1 size:742"}
{"level":"info","ts":"2026-05-25T01:32:04.3072Z","caller":"traceutil/trace.go:171","msg":"trace[1097773895] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18b2aae2f9565b26; range_end:; response_count:1; response_revision:25031; }","duration":"210.597282ms","start":"2026-05-25T01:32:04.096591Z","end":"2026-05-25T01:32:04.307188Z","steps":["trace[1097773895] 'agreement among raft nodes before linearized reading'  (duration: 210.466419ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:04.307259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.671423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"}
{"level":"warn","ts":"2026-05-25T01:32:04.307291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.558181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"}
{"level":"info","ts":"2026-05-25T01:32:04.307352Z","caller":"traceutil/trace.go:171","msg":"trace[420355005] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25031; }","duration":"154.804677ms","start":"2026-05-25T01:32:04.152526Z","end":"2026-05-25T01:32:04.307331Z","steps":["trace[420355005] 'agreement among raft nodes before linearized reading'  (duration: 154.554621ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:32:04.307387Z","caller":"traceutil/trace.go:171","msg":"trace[3333876] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25031; }","duration":"210.700314ms","start":"2026-05-25T01:32:04.096662Z","end":"2026-05-25T01:32:04.307362Z","steps":["trace[3333876] 'agreement among raft nodes before linearized reading'  (duration: 210.423208ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:32:04.521388Z","caller":"traceutil/trace.go:171","msg":"trace[1464536442] linearizableReadLoop","detail":"{readStateIndex:25863; appliedIndex:25862; }","duration":"212.163076ms","start":"2026-05-25T01:32:04.309203Z","end":"2026-05-25T01:32:04.521366Z","steps":["trace[1464536442] 'read index received'  (duration: 133.667683ms)","trace[1464536442] 'applied index is now lower than readState.Index'  (duration: 78.493943ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:32:04.521469Z","caller":"traceutil/trace.go:171","msg":"trace[695592398] transaction","detail":"{read_only:false; response_revision:25032; number_of_response:1; }","duration":"212.257647ms","start":"2026-05-25T01:32:04.309175Z","end":"2026-05-25T01:32:04.521432Z","steps":["trace[695592398] 'process raft request'  (duration: 133.626452ms)","trace[695592398] 'compare'  (duration: 78.350088ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:32:04.521604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.348877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9545"}
{"level":"info","ts":"2026-05-25T01:32:04.521639Z","caller":"traceutil/trace.go:171","msg":"trace[1614786930] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25032; }","duration":"210.412708ms","start":"2026-05-25T01:32:04.311218Z","end":"2026-05-25T01:32:04.521631Z","steps":["trace[1614786930] 'agreement among raft nodes before linearized reading'  (duration: 210.274565ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:04.521605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.37228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"}
{"level":"warn","ts":"2026-05-25T01:32:04.521656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.177057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:32:04.521691Z","caller":"traceutil/trace.go:171","msg":"trace[1453637264] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:25032; }","duration":"212.470022ms","start":"2026-05-25T01:32:04.309199Z","end":"2026-05-25T01:32:04.521669Z","steps":["trace[1453637264] 'agreement among raft nodes before linearized reading'  (duration: 212.230817ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:32:04.521734Z","caller":"traceutil/trace.go:171","msg":"trace[1038493908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25032; }","duration":"142.292728ms","start":"2026-05-25T01:32:04.379423Z","end":"2026-05-25T01:32:04.521716Z","steps":["trace[1038493908] 'agreement among raft nodes before linearized reading'  (duration: 142.178616ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:04.521793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.904189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:32:04.521819Z","caller":"traceutil/trace.go:171","msg":"trace[308601987] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25032; }","duration":"147.96527ms","start":"2026-05-25T01:32:04.373848Z","end":"2026-05-25T01:32:04.521814Z","steps":["trace[308601987] 'agreement among raft nodes before linearized reading'  (duration: 147.92263ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:32:04.72105Z","caller":"traceutil/trace.go:171","msg":"trace[1227233770] linearizableReadLoop","detail":"{readStateIndex:25864; appliedIndex:25863; }","duration":"188.288944ms","start":"2026-05-25T01:32:04.532742Z","end":"2026-05-25T01:32:04.721031Z","steps":["trace[1227233770] 'read index received'  (duration: 123.24219ms)","trace[1227233770] 'applied index is now lower than readState.Index'  (duration: 65.045904ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:32:04.721105Z","caller":"traceutil/trace.go:171","msg":"trace[1348276502] transaction","detail":"{read_only:false; response_revision:25033; number_of_response:1; }","duration":"188.642393ms","start":"2026-05-25T01:32:04.532434Z","end":"2026-05-25T01:32:04.721076Z","steps":["trace[1348276502] 'process raft request'  (duration: 123.572139ms)","trace[1348276502] 'compare'  (duration: 64.936691ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:32:04.721219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.467858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"}
{"level":"info","ts":"2026-05-25T01:32:04.721256Z","caller":"traceutil/trace.go:171","msg":"trace[1644801235] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25033; }","duration":"188.540839ms","start":"2026-05-25T01:32:04.53271Z","end":"2026-05-25T01:32:04.721251Z","steps":["trace[1644801235] 'agreement among raft nodes before linearized reading'  (duration: 188.417237ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:04.721422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.988439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9545"}
{"level":"info","ts":"2026-05-25T01:32:04.721529Z","caller":"traceutil/trace.go:171","msg":"trace[590523009] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25033; }","duration":"182.128582ms","start":"2026-05-25T01:32:04.539378Z","end":"2026-05-25T01:32:04.721506Z","steps":["trace[590523009] 'agreement among raft nodes before linearized reading'  (duration: 181.843626ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:04.929597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.498652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9545"}
{"level":"info","ts":"2026-05-25T01:32:04.929664Z","caller":"traceutil/trace.go:171","msg":"trace[1964041478] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25035; }","duration":"106.586064ms","start":"2026-05-25T01:32:04.823064Z","end":"2026-05-25T01:32:04.92965Z","steps":["trace[1964041478] 'range keys from in-memory index tree'  (duration: 106.402659ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:32:05.158654Z","caller":"traceutil/trace.go:171","msg":"trace[1756730593] transaction","detail":"{read_only:false; response_revision:25036; number_of_response:1; }","duration":"149.228367ms","start":"2026-05-25T01:32:05.009401Z","end":"2026-05-25T01:32:05.158629Z","steps":["trace[1756730593] 'process raft request'  (duration: 149.016542ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:32:05.200246Z","caller":"traceutil/trace.go:171","msg":"trace[94651736] transaction","detail":"{read_only:false; response_revision:25037; number_of_response:1; }","duration":"189.841427ms","start":"2026-05-25T01:32:05.01038Z","end":"2026-05-25T01:32:05.200222Z","steps":["trace[94651736] 'process raft request'  (duration: 189.672224ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:32:05.893199Z","caller":"traceutil/trace.go:171","msg":"trace[677008146] transaction","detail":"{read_only:false; response_revision:25042; number_of_response:1; }","duration":"178.33747ms","start":"2026-05-25T01:32:05.714828Z","end":"2026-05-25T01:32:05.893165Z","steps":["trace[677008146] 'process raft request'  (duration: 103.856695ms)","trace[677008146] 'compare'  (duration: 74.312241ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:32:05.893333Z","caller":"traceutil/trace.go:171","msg":"trace[1037196711] linearizableReadLoop","detail":"{readStateIndex:25873; appliedIndex:25871; }","duration":"161.08088ms","start":"2026-05-25T01:32:05.73223Z","end":"2026-05-25T01:32:05.893311Z","steps":["trace[1037196711] 'read index received'  (duration: 37.514073ms)","trace[1037196711] 'applied index is now lower than readState.Index'  (duration: 123.565567ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-25T01:32:05.893546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.307896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"}
{"level":"info","ts":"2026-05-25T01:32:05.893598Z","caller":"traceutil/trace.go:171","msg":"trace[1355682530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25043; }","duration":"161.391317ms","start":"2026-05-25T01:32:05.732193Z","end":"2026-05-25T01:32:05.893585Z","steps":["trace[1355682530] 'agreement among raft nodes before linearized reading'  (duration: 161.207433ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:05.893568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.248344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-05-25T01:32:05.893779Z","caller":"traceutil/trace.go:171","msg":"trace[1904236793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25043; }","duration":"161.51181ms","start":"2026-05-25T01:32:05.732256Z","end":"2026-05-25T01:32:05.893768Z","steps":["trace[1904236793] 'agreement among raft nodes before linearized reading'  (duration: 161.165592ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:05.893735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.391887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"}
{"level":"info","ts":"2026-05-25T01:32:05.893875Z","caller":"traceutil/trace.go:171","msg":"trace[277712979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25043; }","duration":"161.52187ms","start":"2026-05-25T01:32:05.732337Z","end":"2026-05-25T01:32:05.893859Z","steps":["trace[277712979] 'agreement among raft nodes before linearized reading'  (duration: 161.279995ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:06.147559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.739486ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10836960723862541408 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:25025 > 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\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-25T01:32:06.147641Z","caller":"traceutil/trace.go:171","msg":"trace[1153169682] transaction","detail":"{read_only:false; response_revision:25044; number_of_response:1; }","duration":"244.469588ms","start":"2026-05-25T01:32:05.903155Z","end":"2026-05-25T01:32:06.147625Z","steps":["trace[1153169682] 'process raft request'  (duration: 124.585489ms)","trace[1153169682] 'compare'  (duration: 119.656924ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:32:06.157398Z","caller":"traceutil/trace.go:171","msg":"trace[1389612921] linearizableReadLoop","detail":"{readStateIndex:25877; appliedIndex:25874; }","duration":"171.663488ms","start":"2026-05-25T01:32:05.985717Z","end":"2026-05-25T01:32:06.15738Z","steps":["trace[1389612921] 'read index received'  (duration: 42.047001ms)","trace[1389612921] 'applied index is now lower than readState.Index'  (duration: 129.615697ms)"],"step_count":2}
{"level":"info","ts":"2026-05-25T01:32:06.157434Z","caller":"traceutil/trace.go:171","msg":"trace[2089809123] transaction","detail":"{read_only:false; response_revision:25046; number_of_response:1; }","duration":"254.051303ms","start":"2026-05-25T01:32:05.903365Z","end":"2026-05-25T01:32:06.157417Z","steps":["trace[2089809123] 'process raft request'  (duration: 253.986372ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:32:06.157428Z","caller":"traceutil/trace.go:171","msg":"trace[996333110] transaction","detail":"{read_only:false; response_revision:25045; number_of_response:1; }","duration":"254.099584ms","start":"2026-05-25T01:32:05.903307Z","end":"2026-05-25T01:32:06.157407Z","steps":["trace[996333110] 'process raft request'  (duration: 253.93888ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:06.15754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.822942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"}
{"level":"info","ts":"2026-05-25T01:32:06.157567Z","caller":"traceutil/trace.go:171","msg":"trace[1611175046] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25046; }","duration":"171.885493ms","start":"2026-05-25T01:32:05.985675Z","end":"2026-05-25T01:32:06.15756Z","steps":["trace[1611175046] 'agreement among raft nodes before linearized reading'  (duration: 171.775261ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:06.164182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.524551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:32:06.164225Z","caller":"traceutil/trace.go:171","msg":"trace[550887864] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:25047; }","duration":"175.602632ms","start":"2026-05-25T01:32:05.988611Z","end":"2026-05-25T01:32:06.164214Z","steps":["trace[550887864] 'agreement among raft nodes before linearized reading'  (duration: 175.49942ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-25T01:32:15.116092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.777811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-25T01:32:15.11618Z","caller":"traceutil/trace.go:171","msg":"trace[1484781938] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25119; }","duration":"145.11192ms","start":"2026-05-25T01:32:14.971049Z","end":"2026-05-25T01:32:15.116161Z","steps":["trace[1484781938] 'range keys from in-memory index tree'  (duration: 144.70534ms)"],"step_count":1}
{"level":"info","ts":"2026-05-25T01:34:00.65937Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23264}
{"level":"info","ts":"2026-05-25T01:34:00.796227Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23264,"took":"132.730644ms","hash":2488194344,"current-db-size-bytes":59576320,"current-db-size":"60 MB","current-db-size-in-use-bytes":35930112,"current-db-size-in-use":"36 MB"}
{"level":"info","ts":"2026-05-25T01:34:00.796289Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2488194344,"revision":23264,"compact-revision":20081}
{"level":"info","ts":"2026-05-25T01:34:20.998834Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a2b.wal"}
{"level":"info","ts":"2026-05-25T01:39:00.665917Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26120}
{"level":"info","ts":"2026-05-25T01:39:00.804999Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26120,"took":"135.68425ms","hash":2509308445,"current-db-size-bytes":59576320,"current-db-size":"60 MB","current-db-size-in-use-bytes":33292288,"current-db-size-in-use":"33 MB"}
{"level":"info","ts":"2026-05-25T01:39:00.805066Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2509308445,"revision":26120,"compact-revision":23264}
{"level":"info","ts":"2026-05-25T01:39:43.388552Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ab97322ebdd79664","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-05-25T01:39:43.393446Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003}
{"level":"info","ts":"2026-05-25T01:39:43.393555Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}
{"level":"info","ts":"2026-05-25T01:44:00.677246Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28755}
{"level":"info","ts":"2026-05-25T01:44:00.814593Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28755,"took":"132.928359ms","hash":983469681,"current-db-size-bytes":59576320,"current-db-size":"60 MB","current-db-size-in-use-bytes":29798400,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-05-25T01:44:00.814655Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":983469681,"revision":28755,"compact-revision":26120}
{"level":"info","ts":"2026-05-25T01:49:00.683131Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30977}
{"level":"info","ts":"2026-05-25T01:49:00.795988Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30977,"took":"109.385244ms","hash":2392281640,"current-db-size-bytes":59576320,"current-db-size":"60 MB","current-db-size-in-use-bytes":30658560,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-05-25T01:49:00.79608Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2392281640,"revision":30977,"compact-revision":28755}
{"level":"info","ts":"2026-05-25T01:54:00.695027Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33235}
{"level":"info","ts":"2026-05-25T01:54:00.862694Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33235,"took":"162.842102ms","hash":1751523732,"current-db-size-bytes":60616704,"current-db-size":"61 MB","current-db-size-in-use-bytes":31203328,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-05-25T01:54:00.862756Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1751523732,"revision":33235,"compact-revision":30977}
{"level":"info","ts":"2026-05-25T01:59:00.70021Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35536}
{"level":"info","ts":"2026-05-25T01:59:00.842438Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35536,"took":"138.954689ms","hash":2476569768,"current-db-size-bytes":61931520,"current-db-size":"62 MB","current-db-size-in-use-bytes":30318592,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-05-25T01:59:00.842523Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2476569768,"revision":35536,"compact-revision":33235}
{"level":"info","ts":"2026-05-25T02:00:59.486475Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ab97322ebdd79664","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-05-25T02:00:59.491447Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004}
{"level":"info","ts":"2026-05-25T02:00:59.492686Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}