{"level":"warn","ts":"2026-09-19T10:33:57.558395Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-09-19T10:33:57.558499Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.84:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.84:2380","--initial-cluster=instance=https://162.253.55.84:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.84:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.84:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]}
{"level":"warn","ts":"2026-09-19T10:33:57.558586Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-09-19T10:33:57.558601Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.84:2380"]}
{"level":"info","ts":"2026-09-19T10:33:57.558623Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-09-19T10:33:57.55932Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"]}
{"level":"info","ts":"2026-09-19T10:33:57.559395Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.84:2380"],"listen-peer-urls":["https://162.253.55.84:2380"],"advertise-client-urls":["https://162.253.55.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.84:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"}
{"level":"info","ts":"2026-09-19T10:33:57.568016Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.367997ms"}
{"level":"info","ts":"2026-09-19T10:33:57.578323Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ca4c710a8d5349d7","cluster-id":"8e28a51debf1c8ab"}
{"level":"info","ts":"2026-09-19T10:33:57.578437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=()"}
{"level":"info","ts":"2026-09-19T10:33:57.578465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became follower at term 0"}
{"level":"info","ts":"2026-09-19T10:33:57.578481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ca4c710a8d5349d7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-09-19T10:33:57.57849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became follower at term 1"}
{"level":"info","ts":"2026-09-19T10:33:57.578534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=(14577150384026110423)"}
{"level":"warn","ts":"2026-09-19T10:33:57.585256Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-09-19T10:33:57.587196Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-09-19T10:33:57.588913Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"}
{"level":"info","ts":"2026-09-19T10:33:57.591454Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ca4c710a8d5349d7","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-09-19T10:33:57.591628Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ca4c710a8d5349d7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-09-19T10:33:57.591736Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"}
{"level":"info","ts":"2026-09-19T10:33:57.591907Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"}
{"level":"info","ts":"2026-09-19T10:33:57.591928Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"}
{"level":"info","ts":"2026-09-19T10:33:57.593366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=(14577150384026110423)"}
{"level":"info","ts":"2026-09-19T10:33:57.593906Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8e28a51debf1c8ab","local-member-id":"ca4c710a8d5349d7","added-peer-id":"ca4c710a8d5349d7","added-peer-peer-urls":["https://162.253.55.84:2380"]}
{"level":"info","ts":"2026-09-19T10:33:57.596288Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-09-19T10:33:57.596394Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.84:2380"}
{"level":"info","ts":"2026-09-19T10:33:57.596436Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.84:2380"}
{"level":"info","ts":"2026-09-19T10:33:57.597322Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-09-19T10:33:57.597273Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ca4c710a8d5349d7","initial-advertise-peer-urls":["https://162.253.55.84:2380"],"listen-peer-urls":["https://162.253.55.84:2380"],"advertise-client-urls":["https://162.253.55.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-09-19T10:33:57.779442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 is starting a new election at term 1"}
{"level":"info","ts":"2026-09-19T10:33:57.779482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became pre-candidate at term 1"}
{"level":"info","ts":"2026-09-19T10:33:57.779501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 received MsgPreVoteResp from ca4c710a8d5349d7 at term 1"}
{"level":"info","ts":"2026-09-19T10:33:57.779519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became candidate at term 2"}
{"level":"info","ts":"2026-09-19T10:33:57.779531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 received MsgVoteResp from ca4c710a8d5349d7 at term 2"}
{"level":"info","ts":"2026-09-19T10:33:57.779547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became leader at term 2"}
{"level":"info","ts":"2026-09-19T10:33:57.779564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ca4c710a8d5349d7 elected leader ca4c710a8d5349d7 at term 2"}
{"level":"info","ts":"2026-09-19T10:33:57.78082Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-09-19T10:33:57.780822Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-19T10:33:57.781019Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-09-19T10:33:57.781286Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-09-19T10:33:57.781419Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-09-19T10:33:57.782681Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8e28a51debf1c8ab","local-member-id":"ca4c710a8d5349d7","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-19T10:33:57.782933Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-19T10:33:57.783072Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-19T10:33:57.783802Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.84:2379"}
{"level":"info","ts":"2026-09-19T10:33:57.780774Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ca4c710a8d5349d7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.84:2379]}","request-path":"/0/members/ca4c710a8d5349d7/attributes","cluster-id":"8e28a51debf1c8ab","publish-timeout":"7s"}
{"level":"info","ts":"2026-09-19T10:33:57.784276Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-09-19T10:34:18.229322Z","caller":"traceutil/trace.go:171","msg":"trace[933336408] linearizableReadLoop","detail":"{readStateIndex:395; appliedIndex:394; }","duration":"108.933001ms","start":"2026-09-19T10:34:18.120359Z","end":"2026-09-19T10:34:18.229292Z","steps":["trace[933336408] 'read index received'  (duration: 108.794417ms)","trace[933336408] 'applied index is now lower than readState.Index'  (duration: 137.364µs)"],"step_count":2}
{"level":"info","ts":"2026-09-19T10:34:18.229428Z","caller":"traceutil/trace.go:171","msg":"trace[634054056] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"123.032817ms","start":"2026-09-19T10:34:18.106321Z","end":"2026-09-19T10:34:18.229354Z","steps":["trace[634054056] 'process raft request'  (duration: 122.782941ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T10:34:18.229488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.112536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-09-19T10:34:18.229622Z","caller":"traceutil/trace.go:171","msg":"trace[436874645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:380; }","duration":"109.271159ms","start":"2026-09-19T10:34:18.120301Z","end":"2026-09-19T10:34:18.229572Z","steps":["trace[436874645] 'agreement among raft nodes before linearized reading'  (duration: 109.120936ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T10:34:46.880299Z","caller":"traceutil/trace.go:171","msg":"trace[1691377360] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"291.770997ms","start":"2026-09-19T10:34:46.588458Z","end":"2026-09-19T10:34:46.880229Z","steps":["trace[1691377360] 'read index received'  (duration: 284.628017ms)","trace[1691377360] 'applied index is now lower than readState.Index'  (duration: 7.14164ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T10:34:46.880584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.034922ms","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-09-19T10:34:46.880513Z","caller":"traceutil/trace.go:171","msg":"trace[457802074] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"441.200959ms","start":"2026-09-19T10:34:46.439165Z","end":"2026-09-19T10:34:46.880366Z","steps":["trace[457802074] 'process raft request'  (duration: 438.268233ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T10:34:46.880636Z","caller":"traceutil/trace.go:171","msg":"trace[1771789708] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"182.140215ms","start":"2026-09-19T10:34:46.698481Z","end":"2026-09-19T10:34:46.880621Z","steps":["trace[1771789708] 'agreement among raft nodes before linearized reading'  (duration: 181.965881ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T10:34:46.88071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.198706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kmxjc\" ","response":"range_response_count:1 size:16961"}
{"level":"info","ts":"2026-09-19T10:34:46.880828Z","caller":"traceutil/trace.go:171","msg":"trace[1353169178] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kmxjc; range_end:; response_count:1; response_revision:599; }","duration":"292.417782ms","start":"2026-09-19T10:34:46.588381Z","end":"2026-09-19T10:34:46.880799Z","steps":["trace[1353169178] 'agreement among raft nodes before linearized reading'  (duration: 292.039663ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T10:34:46.881891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:34:46.439138Z","time spent":"441.571078ms","remote":"127.0.0.1:50232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" mod_revision:594 > success:<request_put:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" value_size:1036141 >> failure:<request_range:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" > >"}
{"level":"info","ts":"2026-09-19T10:35:03.099661Z","caller":"traceutil/trace.go:171","msg":"trace[338604453] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"128.391372ms","start":"2026-09-19T10:35:02.971237Z","end":"2026-09-19T10:35:03.099628Z","steps":["trace[338604453] 'process raft request'  (duration: 128.215948ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T10:36:22.331574Z","caller":"traceutil/trace.go:171","msg":"trace[927486045] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"163.794537ms","start":"2026-09-19T10:36:22.167756Z","end":"2026-09-19T10:36:22.33155Z","steps":["trace[927486045] 'process raft request'  (duration: 163.632003ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T10:36:22.347798Z","caller":"traceutil/trace.go:171","msg":"trace[1938056454] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"138.905312ms","start":"2026-09-19T10:36:22.208859Z","end":"2026-09-19T10:36:22.347765Z","steps":["trace[1938056454] 'process raft request'  (duration: 138.690737ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T10:37:06.926916Z","caller":"traceutil/trace.go:171","msg":"trace[828029401] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"163.910518ms","start":"2026-09-19T10:37:06.762851Z","end":"2026-09-19T10:37:06.926761Z","steps":["trace[828029401] 'process raft request'  (duration: 103.48367ms)","trace[828029401] 'compare'  (duration: 60.081801ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T10:37:06.965195Z","caller":"traceutil/trace.go:171","msg":"trace[361223263] linearizableReadLoop","detail":"{readStateIndex:2224; appliedIndex:2222; }","duration":"156.78564ms","start":"2026-09-19T10:37:06.808378Z","end":"2026-09-19T10:37:06.965164Z","steps":["trace[361223263] 'read index received'  (duration: 57.972914ms)","trace[361223263] 'applied index is now lower than readState.Index'  (duration: 98.810866ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T10:37:06.965665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.169638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"}
{"level":"info","ts":"2026-09-19T10:37:06.965769Z","caller":"traceutil/trace.go:171","msg":"trace[1447044599] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2139; }","duration":"157.393873ms","start":"2026-09-19T10:37:06.808353Z","end":"2026-09-19T10:37:06.965747Z","steps":["trace[1447044599] 'agreement among raft nodes before linearized reading'  (duration: 156.917642ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T10:37:06.96564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.010954ms","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-09-19T10:37:06.965956Z","caller":"traceutil/trace.go:171","msg":"trace[123332095] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2139; }","duration":"101.416524ms","start":"2026-09-19T10:37:06.864489Z","end":"2026-09-19T10:37:06.965906Z","steps":["trace[123332095] 'agreement among raft nodes before linearized reading'  (duration: 100.913963ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T10:38:36.599679Z","caller":"traceutil/trace.go:171","msg":"trace[1537971020] transaction","detail":"{read_only:false; response_revision:2819; number_of_response:1; }","duration":"233.495729ms","start":"2026-09-19T10:38:36.366133Z","end":"2026-09-19T10:38:36.599629Z","steps":["trace[1537971020] 'process raft request'  (duration: 140.881429ms)","trace[1537971020] 'compare'  (duration: 92.440057ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T10:43:30.102021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.234664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"}
{"level":"info","ts":"2026-09-19T10:43:30.102132Z","caller":"traceutil/trace.go:171","msg":"trace[2120774123] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5157; }","duration":"135.380568ms","start":"2026-09-19T10:43:29.966729Z","end":"2026-09-19T10:43:30.102109Z","steps":["trace[2120774123] 'range keys from in-memory index tree'  (duration: 135.02151ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T10:43:30.10218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.940936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-19T10:43:30.102298Z","caller":"traceutil/trace.go:171","msg":"trace[607575046] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:5157; }","duration":"139.091231ms","start":"2026-09-19T10:43:29.963177Z","end":"2026-09-19T10:43:30.102269Z","steps":["trace[607575046] 'range keys from in-memory index tree'  (duration: 138.835914ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T10:43:49.358272Z","caller":"traceutil/trace.go:171","msg":"trace[1923438631] linearizableReadLoop","detail":"{readStateIndex:5790; appliedIndex:5789; }","duration":"107.092407ms","start":"2026-09-19T10:43:49.251159Z","end":"2026-09-19T10:43:49.358251Z","steps":["trace[1923438631] 'read index received'  (duration: 106.911593ms)","trace[1923438631] 'applied index is now lower than readState.Index'  (duration: 180.164µs)"],"step_count":2}
{"level":"info","ts":"2026-09-19T10:43:49.358323Z","caller":"traceutil/trace.go:171","msg":"trace[566172689] transaction","detail":"{read_only:false; response_revision:5575; number_of_response:1; }","duration":"163.890583ms","start":"2026-09-19T10:43:49.194405Z","end":"2026-09-19T10:43:49.358295Z","steps":["trace[566172689] 'process raft request'  (duration: 163.719509ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T10:43:49.35852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.297221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-19T10:43:49.358638Z","caller":"traceutil/trace.go:171","msg":"trace[1817898035] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:5575; }","duration":"107.487586ms","start":"2026-09-19T10:43:49.251123Z","end":"2026-09-19T10:43:49.358611Z","steps":["trace[1817898035] 'agreement among raft nodes before linearized reading'  (duration: 107.250831ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T10:43:49.582452Z","caller":"traceutil/trace.go:171","msg":"trace[330352466] transaction","detail":"{read_only:false; response_revision:5576; number_of_response:1; }","duration":"218.95119ms","start":"2026-09-19T10:43:49.363455Z","end":"2026-09-19T10:43:49.582406Z","steps":["trace[330352466] 'process raft request'  (duration: 188.219405ms)","trace[330352466] 'compare'  (duration: 30.610842ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T10:43:58.397487Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2941}
{"level":"info","ts":"2026-09-19T10:43:58.547707Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2941,"took":"148.290135ms","hash":3128924585,"current-db-size-bytes":56496128,"current-db-size":"56 MB","current-db-size-in-use-bytes":31784960,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-09-19T10:43:58.547848Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3128924585,"revision":2941,"compact-revision":-1}
{"level":"info","ts":"2026-09-19T10:48:58.40523Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5779}
{"level":"info","ts":"2026-09-19T10:48:58.500127Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5779,"took":"92.376074ms","hash":843312240,"current-db-size-bytes":56496128,"current-db-size":"56 MB","current-db-size-in-use-bytes":26275840,"current-db-size-in-use":"26 MB"}
{"level":"info","ts":"2026-09-19T10:48:58.500239Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":843312240,"revision":5779,"compact-revision":2941}
{"level":"info","ts":"2026-09-19T10:50:12.30019Z","caller":"traceutil/trace.go:171","msg":"trace[281375709] transaction","detail":"{read_only:false; response_revision:8614; number_of_response:1; }","duration":"136.970958ms","start":"2026-09-19T10:50:12.163191Z","end":"2026-09-19T10:50:12.300162Z","steps":["trace[281375709] 'process raft request'  (duration: 136.785764ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T10:50:12.450682Z","caller":"traceutil/trace.go:171","msg":"trace[1284128399] transaction","detail":"{read_only:false; response_revision:8615; number_of_response:1; }","duration":"268.963373ms","start":"2026-09-19T10:50:12.181682Z","end":"2026-09-19T10:50:12.450646Z","steps":["trace[1284128399] 'process raft request'  (duration: 265.594088ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T10:50:12.452175Z","caller":"traceutil/trace.go:171","msg":"trace[619231401] linearizableReadLoop","detail":"{readStateIndex:8925; appliedIndex:8923; }","duration":"137.226203ms","start":"2026-09-19T10:50:12.314924Z","end":"2026-09-19T10:50:12.45215Z","steps":["trace[619231401] 'read index received'  (duration: 132.372174ms)","trace[619231401] 'applied index is now lower than readState.Index'  (duration: 4.852739ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T10:50:12.452253Z","caller":"traceutil/trace.go:171","msg":"trace[1744889852] transaction","detail":"{read_only:false; response_revision:8616; number_of_response:1; }","duration":"146.070482ms","start":"2026-09-19T10:50:12.306158Z","end":"2026-09-19T10:50:12.452228Z","steps":["trace[1744889852] 'process raft request'  (duration: 145.860356ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T10:50:12.452354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.401507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-19T10:50:12.452422Z","caller":"traceutil/trace.go:171","msg":"trace[1555064475] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:8616; }","duration":"137.53868ms","start":"2026-09-19T10:50:12.314872Z","end":"2026-09-19T10:50:12.45241Z","steps":["trace[1555064475] 'agreement among raft nodes before linearized reading'  (duration: 137.418627ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T10:50:25.622285Z","caller":"traceutil/trace.go:171","msg":"trace[1621952356] transaction","detail":"{read_only:false; response_revision:8679; number_of_response:1; }","duration":"172.588862ms","start":"2026-09-19T10:50:25.449663Z","end":"2026-09-19T10:50:25.622252Z","steps":["trace[1621952356] 'process raft request'  (duration: 172.117832ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T10:50:26.031301Z","caller":"traceutil/trace.go:171","msg":"trace[1978975835] linearizableReadLoop","detail":"{readStateIndex:8995; appliedIndex:8994; }","duration":"107.415048ms","start":"2026-09-19T10:50:25.923857Z","end":"2026-09-19T10:50:26.031272Z","steps":["trace[1978975835] 'read index received'  (duration: 20.12186ms)","trace[1978975835] 'applied index is now lower than readState.Index'  (duration: 87.291808ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T10:50:26.031333Z","caller":"traceutil/trace.go:171","msg":"trace[680918954] transaction","detail":"{read_only:false; response_revision:8683; number_of_response:1; }","duration":"108.063852ms","start":"2026-09-19T10:50:25.923233Z","end":"2026-09-19T10:50:26.031297Z","steps":["trace[680918954] 'process raft request'  (duration: 20.808305ms)","trace[680918954] 'compare'  (duration: 87.082834ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T10:50:26.031612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.733605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-09-19T10:50:26.031731Z","caller":"traceutil/trace.go:171","msg":"trace[1585430244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8683; }","duration":"107.938099ms","start":"2026-09-19T10:50:25.92377Z","end":"2026-09-19T10:50:26.031709Z","steps":["trace[1585430244] 'agreement among raft nodes before linearized reading'  (duration: 107.661253ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T10:50:26.212015Z","caller":"traceutil/trace.go:171","msg":"trace[1850275605] transaction","detail":"{read_only:false; response_revision:8686; number_of_response:1; }","duration":"174.529185ms","start":"2026-09-19T10:50:26.037442Z","end":"2026-09-19T10:50:26.211971Z","steps":["trace[1850275605] 'process raft request'  (duration: 174.402243ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T10:50:26.212055Z","caller":"traceutil/trace.go:171","msg":"trace[1348804223] transaction","detail":"{read_only:false; response_revision:8685; number_of_response:1; }","duration":"175.259151ms","start":"2026-09-19T10:50:26.036738Z","end":"2026-09-19T10:50:26.211997Z","steps":["trace[1348804223] 'process raft request'  (duration: 104.094543ms)","trace[1348804223] 'compare'  (duration: 70.825411ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T10:50:26.212313Z","caller":"traceutil/trace.go:171","msg":"trace[1764079343] transaction","detail":"{read_only:false; response_revision:8687; number_of_response:1; }","duration":"174.28178ms","start":"2026-09-19T10:50:26.037994Z","end":"2026-09-19T10:50:26.212275Z","steps":["trace[1764079343] 'process raft request'  (duration: 173.895132ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T10:50:54.835325Z","caller":"traceutil/trace.go:171","msg":"trace[1788326701] transaction","detail":"{read_only:false; response_revision:8890; number_of_response:1; }","duration":"266.830475ms","start":"2026-09-19T10:50:54.568466Z","end":"2026-09-19T10:50:54.835297Z","steps":["trace[1788326701] 'process raft request'  (duration: 266.645401ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T10:50:55.031523Z","caller":"traceutil/trace.go:171","msg":"trace[1676160436] linearizableReadLoop","detail":"{readStateIndex:9211; appliedIndex:9209; }","duration":"322.263073ms","start":"2026-09-19T10:50:54.709225Z","end":"2026-09-19T10:50:55.031488Z","steps":["trace[1676160436] 'read index received'  (duration: 125.90236ms)","trace[1676160436] 'applied index is now lower than readState.Index'  (duration: 196.359063ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T10:50:55.031885Z","caller":"traceutil/trace.go:171","msg":"trace[35611298] transaction","detail":"{read_only:false; response_revision:8891; number_of_response:1; }","duration":"348.324454ms","start":"2026-09-19T10:50:54.683547Z","end":"2026-09-19T10:50:55.031871Z","steps":["trace[35611298] 'process raft request'  (duration: 341.623235ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T10:50:55.032035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:50:54.683517Z","time spent":"348.407006ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:8878 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-19T10:50:55.032333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.107381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"}
{"level":"info","ts":"2026-09-19T10:50:55.032372Z","caller":"traceutil/trace.go:171","msg":"trace[1127660985] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8891; }","duration":"323.186753ms","start":"2026-09-19T10:50:54.709176Z","end":"2026-09-19T10:50:55.032362Z","steps":["trace[1127660985] 'agreement among raft nodes before linearized reading'  (duration: 323.04391ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T10:50:55.032403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:50:54.709154Z","time spent":"323.239104ms","remote":"127.0.0.1:49058","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-09-19T10:50:55.032557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.812352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-19T10:50:55.032589Z","caller":"traceutil/trace.go:171","msg":"trace[681350048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8891; }","duration":"248.843333ms","start":"2026-09-19T10:50:54.783737Z","end":"2026-09-19T10:50:55.03258Z","steps":["trace[681350048] 'agreement among raft nodes before linearized reading'  (duration: 248.790352ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T10:51:04.361133Z","caller":"traceutil/trace.go:171","msg":"trace[1757440884] transaction","detail":"{read_only:false; response_revision:8940; number_of_response:1; }","duration":"103.630373ms","start":"2026-09-19T10:51:04.257455Z","end":"2026-09-19T10:51:04.361085Z","steps":["trace[1757440884] 'process raft request'  (duration: 103.381357ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T10:51:08.886994Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002449.wal"}
{"level":"info","ts":"2026-09-19T10:52:13.65803Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca4c710a8d5349d7","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-09-19T10:52:13.665627Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-09-19T10:52:13.665855Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"info","ts":"2026-09-19T10:52:35.38771Z","caller":"traceutil/trace.go:171","msg":"trace[1078096150] transaction","detail":"{read_only:false; response_revision:9809; number_of_response:1; }","duration":"135.057112ms","start":"2026-09-19T10:52:35.252612Z","end":"2026-09-19T10:52:35.387669Z","steps":["trace[1078096150] 'process raft request'  (duration: 124.888185ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T10:52:35.675643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.194917ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5320898202178464199 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:9800 > 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\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-19T10:52:35.675812Z","caller":"traceutil/trace.go:171","msg":"trace[1412948777] transaction","detail":"{read_only:false; response_revision:9810; number_of_response:1; }","duration":"206.456375ms","start":"2026-09-19T10:52:35.469314Z","end":"2026-09-19T10:52:35.675771Z","steps":["trace[1412948777] 'process raft request'  (duration: 61.506802ms)","trace[1412948777] 'compare'  (duration: 143.890369ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T10:53:58.415244Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7992}
{"level":"info","ts":"2026-09-19T10:53:58.492449Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7992,"took":"74.210775ms","hash":713778847,"current-db-size-bytes":56496128,"current-db-size":"56 MB","current-db-size-in-use-bytes":28123136,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-09-19T10:53:58.49252Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":713778847,"revision":7992,"compact-revision":5779}
{"level":"warn","ts":"2026-09-19T10:58:17.060568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.687335ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5320898202178470877 > lease_revoke:<id:49d7a0b93adcc2a8>","response":"size:29"}
{"level":"info","ts":"2026-09-19T10:58:58.430024Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10382}
{"level":"info","ts":"2026-09-19T10:58:58.52042Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10382,"took":"87.490419ms","hash":2117592512,"current-db-size-bytes":56496128,"current-db-size":"56 MB","current-db-size-in-use-bytes":25698304,"current-db-size-in-use":"26 MB"}
{"level":"info","ts":"2026-09-19T10:58:58.520513Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2117592512,"revision":10382,"compact-revision":7992}
{"level":"info","ts":"2026-09-19T11:03:58.443583Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12556}
{"level":"info","ts":"2026-09-19T11:03:58.529559Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12556,"took":"82.246453ms","hash":55525535,"current-db-size-bytes":56496128,"current-db-size":"56 MB","current-db-size-in-use-bytes":27684864,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-09-19T11:03:58.529688Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":55525535,"revision":12556,"compact-revision":10382}
{"level":"info","ts":"2026-09-19T11:05:00.761786Z","caller":"traceutil/trace.go:171","msg":"trace[2014684817] linearizableReadLoop","detail":"{readStateIndex:15475; appliedIndex:15474; }","duration":"221.166077ms","start":"2026-09-19T11:05:00.540592Z","end":"2026-09-19T11:05:00.761758Z","steps":["trace[2014684817] 'read index received'  (duration: 220.867141ms)","trace[2014684817] 'applied index is now lower than readState.Index'  (duration: 297.916µs)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:05:00.762207Z","caller":"traceutil/trace.go:171","msg":"trace[1754915333] transaction","detail":"{read_only:false; response_revision:14949; number_of_response:1; }","duration":"235.046447ms","start":"2026-09-19T11:05:00.527143Z","end":"2026-09-19T11:05:00.762189Z","steps":["trace[1754915333] 'process raft request'  (duration: 234.406343ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:05:00.76275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.145449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/cinder-volume-usage-audit\" ","response":"range_response_count:1 size:552"}
{"level":"info","ts":"2026-09-19T11:05:00.762798Z","caller":"traceutil/trace.go:171","msg":"trace[573661421] range","detail":"{range_begin:/registry/serviceaccounts/openstack/cinder-volume-usage-audit; range_end:; response_count:1; response_revision:14949; }","duration":"222.261662ms","start":"2026-09-19T11:05:00.54052Z","end":"2026-09-19T11:05:00.762782Z","steps":["trace[573661421] 'agreement among raft nodes before linearized reading'  (duration: 221.896124ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:05:00.763189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.265674ms","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:7"}
{"level":"info","ts":"2026-09-19T11:05:00.763293Z","caller":"traceutil/trace.go:171","msg":"trace[1316090108] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:14949; }","duration":"109.445128ms","start":"2026-09-19T11:05:00.653825Z","end":"2026-09-19T11:05:00.76327Z","steps":["trace[1316090108] 'agreement among raft nodes before linearized reading'  (duration: 109.209023ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:05:08.285296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.852851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:86 size:930862"}
{"level":"info","ts":"2026-09-19T11:05:08.285412Z","caller":"traceutil/trace.go:171","msg":"trace[2145941517] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:86; response_revision:15015; }","duration":"103.048005ms","start":"2026-09-19T11:05:08.182341Z","end":"2026-09-19T11:05:08.285389Z","steps":["trace[2145941517] 'range keys from in-memory index tree'  (duration: 101.831148ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:06:38.022955Z","caller":"traceutil/trace.go:171","msg":"trace[51827173] linearizableReadLoop","detail":"{readStateIndex:16731; appliedIndex:16730; }","duration":"130.748723ms","start":"2026-09-19T11:06:37.892173Z","end":"2026-09-19T11:06:38.022921Z","steps":["trace[51827173] 'read index received'  (duration: 130.441306ms)","trace[51827173] 'applied index is now lower than readState.Index'  (duration: 305.627µs)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:06:38.023022Z","caller":"traceutil/trace.go:171","msg":"trace[1704623687] transaction","detail":"{read_only:false; response_revision:16169; number_of_response:1; }","duration":"136.068771ms","start":"2026-09-19T11:06:37.886909Z","end":"2026-09-19T11:06:38.022978Z","steps":["trace[1704623687] 'process raft request'  (duration: 135.811916ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:06:38.023324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.132691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-gxbx9\" ","response":"range_response_count:1 size:22121"}
{"level":"info","ts":"2026-09-19T11:06:38.02343Z","caller":"traceutil/trace.go:171","msg":"trace[1750546081] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-gxbx9; range_end:; response_count:1; response_revision:16169; }","duration":"131.316776ms","start":"2026-09-19T11:06:37.892091Z","end":"2026-09-19T11:06:38.023408Z","steps":["trace[1750546081] 'agreement among raft nodes before linearized reading'  (duration: 130.984948ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:06:38.023556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.149247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-gxbx9\" ","response":"range_response_count:1 size:22121"}
{"level":"info","ts":"2026-09-19T11:06:38.023652Z","caller":"traceutil/trace.go:171","msg":"trace[830751551] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-gxbx9; range_end:; response_count:1; response_revision:16169; }","duration":"129.299831ms","start":"2026-09-19T11:06:37.894335Z","end":"2026-09-19T11:06:38.023635Z","steps":["trace[830751551] 'agreement among raft nodes before linearized reading'  (duration: 128.949503ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:06:44.029716Z","caller":"traceutil/trace.go:171","msg":"trace[471600902] transaction","detail":"{read_only:false; response_revision:16202; number_of_response:1; }","duration":"190.300099ms","start":"2026-09-19T11:06:43.839386Z","end":"2026-09-19T11:06:44.029686Z","steps":["trace[471600902] 'process raft request'  (duration: 189.848089ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:06:44.259128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.206282ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5320898202178482502 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:16191 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-19T11:06:44.259267Z","caller":"traceutil/trace.go:171","msg":"trace[1490219608] linearizableReadLoop","detail":"{readStateIndex:16766; appliedIndex:16765; }","duration":"149.443829ms","start":"2026-09-19T11:06:44.109804Z","end":"2026-09-19T11:06:44.259247Z","steps":["trace[1490219608] 'read index received'  (duration: 22.920121ms)","trace[1490219608] 'applied index is now lower than readState.Index'  (duration: 126.522328ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:06:44.259435Z","caller":"traceutil/trace.go:171","msg":"trace[1867679103] transaction","detail":"{read_only:false; response_revision:16203; number_of_response:1; }","duration":"215.384978ms","start":"2026-09-19T11:06:44.044012Z","end":"2026-09-19T11:06:44.259397Z","steps":["trace[1867679103] 'process raft request'  (duration: 88.797529ms)","trace[1867679103] 'compare'  (duration: 126.029547ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:06:44.259744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.91716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"info","ts":"2026-09-19T11:06:44.259846Z","caller":"traceutil/trace.go:171","msg":"trace[1984388000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16203; }","duration":"150.076102ms","start":"2026-09-19T11:06:44.109741Z","end":"2026-09-19T11:06:44.259818Z","steps":["trace[1984388000] 'agreement among raft nodes before linearized reading'  (duration: 149.723165ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:06:44.25997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.249286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-09-19T11:06:44.260086Z","caller":"traceutil/trace.go:171","msg":"trace[828560846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16203; }","duration":"145.393839ms","start":"2026-09-19T11:06:44.114665Z","end":"2026-09-19T11:06:44.260058Z","steps":["trace[828560846] 'agreement among raft nodes before linearized reading'  (duration: 145.093512ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:06:44.260187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.458509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-gxbx9\" ","response":"range_response_count:1 size:22121"}
{"level":"info","ts":"2026-09-19T11:06:44.260253Z","caller":"traceutil/trace.go:171","msg":"trace[2112960165] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-gxbx9; range_end:; response_count:1; response_revision:16203; }","duration":"140.542391ms","start":"2026-09-19T11:06:44.119696Z","end":"2026-09-19T11:06:44.260238Z","steps":["trace[2112960165] 'agreement among raft nodes before linearized reading'  (duration: 140.342106ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:06:44.260334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.722984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-gxbx9\" ","response":"range_response_count:1 size:22121"}
{"level":"info","ts":"2026-09-19T11:06:44.260388Z","caller":"traceutil/trace.go:171","msg":"trace[1515118552] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-gxbx9; range_end:; response_count:1; response_revision:16203; }","duration":"140.825077ms","start":"2026-09-19T11:06:44.119546Z","end":"2026-09-19T11:06:44.260371Z","steps":["trace[1515118552] 'agreement among raft nodes before linearized reading'  (duration: 140.667363ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:06:44.260752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.16418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"info","ts":"2026-09-19T11:06:44.260815Z","caller":"traceutil/trace.go:171","msg":"trace[381946679] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16203; }","duration":"143.249851ms","start":"2026-09-19T11:06:44.117547Z","end":"2026-09-19T11:06:44.260796Z","steps":["trace[381946679] 'agreement among raft nodes before linearized reading'  (duration: 143.109158ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:08:58.455268Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14562}
{"level":"info","ts":"2026-09-19T11:08:58.538978Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14562,"took":"78.876588ms","hash":256184727,"current-db-size-bytes":56496128,"current-db-size":"56 MB","current-db-size-in-use-bytes":31625216,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-09-19T11:08:58.539128Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":256184727,"revision":14562,"compact-revision":12556}
{"level":"info","ts":"2026-09-19T11:12:29.93057Z","caller":"traceutil/trace.go:171","msg":"trace[886414190] linearizableReadLoop","detail":"{readStateIndex:19339; appliedIndex:19338; }","duration":"146.799831ms","start":"2026-09-19T11:12:29.783738Z","end":"2026-09-19T11:12:29.930538Z","steps":["trace[886414190] 'read index received'  (duration: 146.548464ms)","trace[886414190] 'applied index is now lower than readState.Index'  (duration: 249.956µs)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:12:29.930635Z","caller":"traceutil/trace.go:171","msg":"trace[99271007] transaction","detail":"{read_only:false; response_revision:18691; number_of_response:1; }","duration":"165.416786ms","start":"2026-09-19T11:12:29.765168Z","end":"2026-09-19T11:12:29.930585Z","steps":["trace[99271007] 'process raft request'  (duration: 165.125478ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:12:29.930837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.056506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:12:29.930939Z","caller":"traceutil/trace.go:171","msg":"trace[2000224701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18691; }","duration":"147.20091ms","start":"2026-09-19T11:12:29.783712Z","end":"2026-09-19T11:12:29.930913Z","steps":["trace[2000224701] 'agreement among raft nodes before linearized reading'  (duration: 146.991915ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:12:36.964225Z","caller":"traceutil/trace.go:171","msg":"trace[1376541586] transaction","detail":"{read_only:false; response_revision:18731; number_of_response:1; }","duration":"127.586142ms","start":"2026-09-19T11:12:36.836604Z","end":"2026-09-19T11:12:36.96419Z","steps":["trace[1376541586] 'process raft request'  (duration: 83.157892ms)","trace[1376541586] 'compare'  (duration: 44.146563ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:12:37.927428Z","caller":"traceutil/trace.go:171","msg":"trace[2019342007] linearizableReadLoop","detail":"{readStateIndex:19383; appliedIndex:19382; }","duration":"242.543642ms","start":"2026-09-19T11:12:37.68486Z","end":"2026-09-19T11:12:37.927404Z","steps":["trace[2019342007] 'read index received'  (duration: 242.293507ms)","trace[2019342007] 'applied index is now lower than readState.Index'  (duration: 249.065µs)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:12:37.92754Z","caller":"traceutil/trace.go:171","msg":"trace[308609024] transaction","detail":"{read_only:false; response_revision:18733; number_of_response:1; }","duration":"270.126687ms","start":"2026-09-19T11:12:37.657357Z","end":"2026-09-19T11:12:37.927484Z","steps":["trace[308609024] 'process raft request'  (duration: 269.869491ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:12:37.927604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.720116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-09-19T11:12:37.927726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.707422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-8clfx\" ","response":"range_response_count:1 size:9799"}
{"level":"info","ts":"2026-09-19T11:12:37.927729Z","caller":"traceutil/trace.go:171","msg":"trace[718072788] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:18733; }","duration":"242.919701ms","start":"2026-09-19T11:12:37.684773Z","end":"2026-09-19T11:12:37.927693Z","steps":["trace[718072788] 'agreement among raft nodes before linearized reading'  (duration: 242.726126ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:12:37.927771Z","caller":"traceutil/trace.go:171","msg":"trace[1342631697] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-8clfx; range_end:; response_count:1; response_revision:18733; }","duration":"108.809664ms","start":"2026-09-19T11:12:37.818948Z","end":"2026-09-19T11:12:37.927758Z","steps":["trace[1342631697] 'agreement among raft nodes before linearized reading'  (duration: 108.60358ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:13:27.257553Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca4c710a8d5349d7","local-member-applied-index":20003,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-09-19T11:13:27.27617Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003}
{"level":"info","ts":"2026-09-19T11:13:27.27628Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003}
{"level":"info","ts":"2026-09-19T11:13:58.462761Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17103}
{"level":"info","ts":"2026-09-19T11:13:58.57407Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17103,"took":"101.623613ms","hash":2895457589,"current-db-size-bytes":56496128,"current-db-size":"56 MB","current-db-size-in-use-bytes":33841152,"current-db-size-in-use":"34 MB"}
{"level":"info","ts":"2026-09-19T11:13:58.574159Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2895457589,"revision":17103,"compact-revision":14562}
{"level":"info","ts":"2026-09-19T11:18:16.632852Z","caller":"traceutil/trace.go:171","msg":"trace[191832525] transaction","detail":"{read_only:false; response_revision:21407; number_of_response:1; }","duration":"106.815649ms","start":"2026-09-19T11:18:16.526007Z","end":"2026-09-19T11:18:16.632823Z","steps":["trace[191832525] 'process raft request'  (duration: 106.625435ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:18:16.635682Z","caller":"traceutil/trace.go:171","msg":"trace[171422888] linearizableReadLoop","detail":"{readStateIndex:22144; appliedIndex:22142; }","duration":"100.022599ms","start":"2026-09-19T11:18:16.535633Z","end":"2026-09-19T11:18:16.635655Z","steps":["trace[171422888] 'read index received'  (duration: 97.083183ms)","trace[171422888] 'applied index is now lower than readState.Index'  (duration: 2.938266ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:18:16.635836Z","caller":"traceutil/trace.go:171","msg":"trace[558896322] transaction","detail":"{read_only:false; response_revision:21408; number_of_response:1; }","duration":"108.350374ms","start":"2026-09-19T11:18:16.527424Z","end":"2026-09-19T11:18:16.635774Z","steps":["trace[558896322] 'process raft request'  (duration: 108.070908ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:18:16.636072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.410588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"}
{"level":"info","ts":"2026-09-19T11:18:16.636142Z","caller":"traceutil/trace.go:171","msg":"trace[686792706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21408; }","duration":"100.51798ms","start":"2026-09-19T11:18:16.535604Z","end":"2026-09-19T11:18:16.636122Z","steps":["trace[686792706] 'agreement among raft nodes before linearized reading'  (duration: 100.267374ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:18:38.686771Z","caller":"traceutil/trace.go:171","msg":"trace[2005584995] transaction","detail":"{read_only:false; response_revision:21520; number_of_response:1; }","duration":"129.239179ms","start":"2026-09-19T11:18:38.557489Z","end":"2026-09-19T11:18:38.686729Z","steps":["trace[2005584995] 'process raft request'  (duration: 113.799004ms)","trace[2005584995] 'compare'  (duration: 15.22994ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:18:38.972114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.367809ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5320898202178500497 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:21508 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-19T11:18:38.97227Z","caller":"traceutil/trace.go:171","msg":"trace[907001559] linearizableReadLoop","detail":"{readStateIndex:22263; appliedIndex:22262; }","duration":"194.29155ms","start":"2026-09-19T11:18:38.777955Z","end":"2026-09-19T11:18:38.972247Z","steps":["trace[907001559] 'read index received'  (duration: 48.260965ms)","trace[907001559] 'applied index is now lower than readState.Index'  (duration: 146.027905ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:18:38.972345Z","caller":"traceutil/trace.go:171","msg":"trace[1540318102] transaction","detail":"{read_only:false; response_revision:21521; number_of_response:1; }","duration":"202.198556ms","start":"2026-09-19T11:18:38.770085Z","end":"2026-09-19T11:18:38.972283Z","steps":["trace[1540318102] 'process raft request'  (duration: 56.219093ms)","trace[1540318102] 'compare'  (duration: 145.100713ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:18:38.9725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.553206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"info","ts":"2026-09-19T11:18:38.972547Z","caller":"traceutil/trace.go:171","msg":"trace[1293047702] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21521; }","duration":"194.634077ms","start":"2026-09-19T11:18:38.777895Z","end":"2026-09-19T11:18:38.972529Z","steps":["trace[1293047702] 'agreement among raft nodes before linearized reading'  (duration: 194.469453ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:18:38.97285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.552508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-09-19T11:18:38.972885Z","caller":"traceutil/trace.go:171","msg":"trace[914721555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21521; }","duration":"182.614198ms","start":"2026-09-19T11:18:38.790261Z","end":"2026-09-19T11:18:38.972876Z","steps":["trace[914721555] 'agreement among raft nodes before linearized reading'  (duration: 182.517106ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:18:38.972884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.5693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:18:38.973011Z","caller":"traceutil/trace.go:171","msg":"trace[1090241822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21521; }","duration":"187.767203ms","start":"2026-09-19T11:18:38.785199Z","end":"2026-09-19T11:18:38.972966Z","steps":["trace[1090241822] 'agreement among raft nodes before linearized reading'  (duration: 187.565609ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:18:38.973032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.316394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"}
{"level":"info","ts":"2026-09-19T11:18:38.973119Z","caller":"traceutil/trace.go:171","msg":"trace[657863704] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21521; }","duration":"187.505648ms","start":"2026-09-19T11:18:38.785563Z","end":"2026-09-19T11:18:38.973068Z","steps":["trace[657863704] 'agreement among raft nodes before linearized reading'  (duration: 187.221001ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:18:39.153862Z","caller":"traceutil/trace.go:171","msg":"trace[1868016032] transaction","detail":"{read_only:false; response_revision:21524; number_of_response:1; }","duration":"175.09537ms","start":"2026-09-19T11:18:38.97873Z","end":"2026-09-19T11:18:39.153826Z","steps":["trace[1868016032] 'process raft request'  (duration: 174.982038ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:18:39.153905Z","caller":"traceutil/trace.go:171","msg":"trace[651875878] transaction","detail":"{read_only:false; response_revision:21523; number_of_response:1; }","duration":"175.254984ms","start":"2026-09-19T11:18:38.97859Z","end":"2026-09-19T11:18:39.153844Z","steps":["trace[651875878] 'process raft request'  (duration: 174.945547ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:18:39.154053Z","caller":"traceutil/trace.go:171","msg":"trace[1028841867] transaction","detail":"{read_only:false; response_revision:21525; number_of_response:1; }","duration":"175.101981ms","start":"2026-09-19T11:18:38.978916Z","end":"2026-09-19T11:18:39.154018Z","steps":["trace[1028841867] 'process raft request'  (duration: 174.852935ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:18:58.470384Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19628}
{"level":"info","ts":"2026-09-19T11:18:58.605265Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19628,"took":"130.5676ms","hash":349994782,"current-db-size-bytes":56496128,"current-db-size":"56 MB","current-db-size-in-use-bytes":29057024,"current-db-size-in-use":"29 MB"}
{"level":"info","ts":"2026-09-19T11:18:58.605379Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":349994782,"revision":19628,"compact-revision":17103}
{"level":"info","ts":"2026-09-19T11:22:40.93415Z","caller":"traceutil/trace.go:171","msg":"trace[1569726475] transaction","detail":"{read_only:false; response_revision:24018; number_of_response:1; }","duration":"294.86971ms","start":"2026-09-19T11:22:40.639247Z","end":"2026-09-19T11:22:40.934117Z","steps":["trace[1569726475] 'process raft request'  (duration: 265.321932ms)","trace[1569726475] 'compare'  (duration: 29.306652ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:22:40.934288Z","caller":"traceutil/trace.go:171","msg":"trace[2044650686] linearizableReadLoop","detail":"{readStateIndex:24835; appliedIndex:24833; }","duration":"211.014261ms","start":"2026-09-19T11:22:40.723239Z","end":"2026-09-19T11:22:40.934253Z","steps":["trace[2044650686] 'read index received'  (duration: 181.3321ms)","trace[2044650686] 'applied index is now lower than readState.Index'  (duration: 29.680241ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:22:40.934332Z","caller":"traceutil/trace.go:171","msg":"trace[2015119396] transaction","detail":"{read_only:false; response_revision:24019; number_of_response:1; }","duration":"282.047274ms","start":"2026-09-19T11:22:40.652249Z","end":"2026-09-19T11:22:40.934296Z","steps":["trace[2015119396] 'process raft request'  (duration: 281.800508ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:22:40.934655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.847298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-09-19T11:22:40.934663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.40603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"}
{"level":"info","ts":"2026-09-19T11:22:40.934707Z","caller":"traceutil/trace.go:171","msg":"trace[1120519982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24019; }","duration":"149.98355ms","start":"2026-09-19T11:22:40.784712Z","end":"2026-09-19T11:22:40.934695Z","steps":["trace[1120519982] 'agreement among raft nodes before linearized reading'  (duration: 149.880398ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:22:40.934718Z","caller":"traceutil/trace.go:171","msg":"trace[234164096] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24019; }","duration":"211.553773ms","start":"2026-09-19T11:22:40.723147Z","end":"2026-09-19T11:22:40.934701Z","steps":["trace[234164096] 'agreement among raft nodes before linearized reading'  (duration: 211.277306ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:22:40.934764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.607333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"info","ts":"2026-09-19T11:22:40.93484Z","caller":"traceutil/trace.go:171","msg":"trace[834716190] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24019; }","duration":"167.717296ms","start":"2026-09-19T11:22:40.767099Z","end":"2026-09-19T11:22:40.934816Z","steps":["trace[834716190] 'agreement among raft nodes before linearized reading'  (duration: 167.479531ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:23:58.477983Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21624}
{"level":"info","ts":"2026-09-19T11:23:58.592925Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21624,"took":"109.843989ms","hash":152213307,"current-db-size-bytes":57339904,"current-db-size":"57 MB","current-db-size-in-use-bytes":43192320,"current-db-size-in-use":"43 MB"}
{"level":"info","ts":"2026-09-19T11:23:58.593036Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":152213307,"revision":21624,"compact-revision":19628}
{"level":"info","ts":"2026-09-19T11:26:11.810649Z","caller":"traceutil/trace.go:171","msg":"trace[1498547010] transaction","detail":"{read_only:false; response_revision:26135; number_of_response:1; }","duration":"122.271776ms","start":"2026-09-19T11:26:11.688349Z","end":"2026-09-19T11:26:11.810621Z","steps":["trace[1498547010] 'process raft request'  (duration: 122.101842ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:11.824352Z","caller":"traceutil/trace.go:171","msg":"trace[2100312817] transaction","detail":"{read_only:false; response_revision:26136; number_of_response:1; }","duration":"134.262864ms","start":"2026-09-19T11:26:11.690071Z","end":"2026-09-19T11:26:11.824334Z","steps":["trace[2100312817] 'process raft request'  (duration: 133.876215ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:12.103316Z","caller":"traceutil/trace.go:171","msg":"trace[1810845034] linearizableReadLoop","detail":"{readStateIndex:27006; appliedIndex:27005; }","duration":"161.459481ms","start":"2026-09-19T11:26:11.941831Z","end":"2026-09-19T11:26:12.103291Z","steps":["trace[1810845034] 'read index received'  (duration: 161.307278ms)","trace[1810845034] 'applied index is now lower than readState.Index'  (duration: 150.823µs)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:12.10371Z","caller":"traceutil/trace.go:171","msg":"trace[220795277] transaction","detail":"{read_only:false; response_revision:26137; number_of_response:1; }","duration":"228.281841ms","start":"2026-09-19T11:26:11.875416Z","end":"2026-09-19T11:26:12.103698Z","steps":["trace[220795277] 'process raft request'  (duration: 227.663507ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:12.103966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.127946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.84\" ","response":"range_response_count:1 size:137"}
{"level":"info","ts":"2026-09-19T11:26:12.103987Z","caller":"traceutil/trace.go:171","msg":"trace[930407152] range","detail":"{range_begin:/registry/masterleases/162.253.55.84; range_end:; response_count:1; response_revision:26137; }","duration":"162.214148ms","start":"2026-09-19T11:26:11.941765Z","end":"2026-09-19T11:26:12.103979Z","steps":["trace[930407152] 'agreement among raft nodes before linearized reading'  (duration: 162.091706ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:12.279302Z","caller":"traceutil/trace.go:171","msg":"trace[637077553] transaction","detail":"{read_only:false; response_revision:26139; number_of_response:1; }","duration":"163.025756ms","start":"2026-09-19T11:26:12.116246Z","end":"2026-09-19T11:26:12.279272Z","steps":["trace[637077553] 'process raft request'  (duration: 82.751086ms)","trace[637077553] 'compare'  (duration: 79.778678ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:12.523792Z","caller":"traceutil/trace.go:171","msg":"trace[193553038] linearizableReadLoop","detail":"{readStateIndex:27012; appliedIndex:27011; }","duration":"239.736338ms","start":"2026-09-19T11:26:12.284021Z","end":"2026-09-19T11:26:12.523757Z","steps":["trace[193553038] 'read index received'  (duration: 239.556474ms)","trace[193553038] 'applied index is now lower than readState.Index'  (duration: 178.354µs)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:12.523861Z","caller":"traceutil/trace.go:171","msg":"trace[1467520897] transaction","detail":"{read_only:false; response_revision:26142; number_of_response:1; }","duration":"240.658368ms","start":"2026-09-19T11:26:12.283156Z","end":"2026-09-19T11:26:12.523814Z","steps":["trace[1467520897] 'process raft request'  (duration: 240.407562ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:12.524238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.164537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:137"}
{"level":"info","ts":"2026-09-19T11:26:12.5243Z","caller":"traceutil/trace.go:171","msg":"trace[1799428430] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:26142; }","duration":"240.293799ms","start":"2026-09-19T11:26:12.283991Z","end":"2026-09-19T11:26:12.524285Z","steps":["trace[1799428430] 'agreement among raft nodes before linearized reading'  (duration: 239.992213ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:12.54614Z","caller":"traceutil/trace.go:171","msg":"trace[877269063] transaction","detail":"{read_only:false; response_revision:26143; number_of_response:1; }","duration":"248.40904ms","start":"2026-09-19T11:26:12.286501Z","end":"2026-09-19T11:26:12.53491Z","steps":["trace[877269063] 'process raft request'  (duration: 246.952487ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:12.546908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.063733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"warn","ts":"2026-09-19T11:26:12.54693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.238672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:26:12.546988Z","caller":"traceutil/trace.go:171","msg":"trace[1175761532] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26144; }","duration":"157.156195ms","start":"2026-09-19T11:26:12.389784Z","end":"2026-09-19T11:26:12.54694Z","steps":["trace[1175761532] 'agreement among raft nodes before linearized reading'  (duration: 156.909209ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:12.547028Z","caller":"traceutil/trace.go:171","msg":"trace[897335202] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:26144; }","duration":"198.368725ms","start":"2026-09-19T11:26:12.348614Z","end":"2026-09-19T11:26:12.546982Z","steps":["trace[897335202] 'agreement among raft nodes before linearized reading'  (duration: 198.233292ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:12.548136Z","caller":"traceutil/trace.go:171","msg":"trace[251645180] transaction","detail":"{read_only:false; response_revision:26144; number_of_response:1; }","duration":"218.573996ms","start":"2026-09-19T11:26:12.3285Z","end":"2026-09-19T11:26:12.547074Z","steps":["trace[251645180] 'process raft request'  (duration: 206.368464ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:16.881729Z","caller":"traceutil/trace.go:171","msg":"trace[390071982] linearizableReadLoop","detail":"{readStateIndex:27048; appliedIndex:27046; }","duration":"309.669567ms","start":"2026-09-19T11:26:16.572028Z","end":"2026-09-19T11:26:16.881697Z","steps":["trace[390071982] 'read index received'  (duration: 54.569158ms)","trace[390071982] 'applied index is now lower than readState.Index'  (duration: 255.097869ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:26:16.882063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.015565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-09-19T11:26:16.882119Z","caller":"traceutil/trace.go:171","msg":"trace[1380901434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26179; }","duration":"310.109597ms","start":"2026-09-19T11:26:16.571994Z","end":"2026-09-19T11:26:16.882104Z","steps":["trace[1380901434] 'agreement among raft nodes before linearized reading'  (duration: 309.871411ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:16.882159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:16.571984Z","time spent":"310.165748ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-09-19T11:26:16.882307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.793548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"info","ts":"2026-09-19T11:26:16.882432Z","caller":"traceutil/trace.go:171","msg":"trace[1928479655] transaction","detail":"{read_only:false; response_revision:26178; number_of_response:1; }","duration":"320.035758ms","start":"2026-09-19T11:26:16.56154Z","end":"2026-09-19T11:26:16.881576Z","steps":["trace[1928479655] 'process raft request'  (duration: 295.667055ms)","trace[1928479655] 'compare'  (duration: 23.548584ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:16.882458Z","caller":"traceutil/trace.go:171","msg":"trace[1730184736] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26179; }","duration":"305.026914ms","start":"2026-09-19T11:26:16.5774Z","end":"2026-09-19T11:26:16.882427Z","steps":["trace[1730184736] 'agreement among raft nodes before linearized reading'  (duration: 304.643635ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:16.882312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.74584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"}
{"level":"warn","ts":"2026-09-19T11:26:16.882536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:16.577371Z","time spent":"305.150416ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"info","ts":"2026-09-19T11:26:16.882552Z","caller":"traceutil/trace.go:171","msg":"trace[712078019] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26179; }","duration":"106.999376ms","start":"2026-09-19T11:26:16.775527Z","end":"2026-09-19T11:26:16.882526Z","steps":["trace[712078019] 'agreement among raft nodes before linearized reading'  (duration: 106.540746ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:16.882621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:16.561517Z","time spent":"320.99886ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:26164 > 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":"info","ts":"2026-09-19T11:26:17.049106Z","caller":"traceutil/trace.go:171","msg":"trace[1931015489] transaction","detail":"{read_only:false; response_revision:26180; number_of_response:1; }","duration":"162.045894ms","start":"2026-09-19T11:26:16.887021Z","end":"2026-09-19T11:26:17.049067Z","steps":["trace[1931015489] 'process raft request'  (duration: 120.861806ms)","trace[1931015489] 'compare'  (duration: 40.990814ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:17.207389Z","caller":"traceutil/trace.go:171","msg":"trace[867779792] transaction","detail":"{read_only:false; response_revision:26181; number_of_response:1; }","duration":"318.536195ms","start":"2026-09-19T11:26:16.888784Z","end":"2026-09-19T11:26:17.207321Z","steps":["trace[867779792] 'process raft request'  (duration: 293.914406ms)","trace[867779792] 'compare'  (duration: 24.302702ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:17.207556Z","caller":"traceutil/trace.go:171","msg":"trace[2010036242] linearizableReadLoop","detail":"{readStateIndex:27052; appliedIndex:27050; }","duration":"180.626549ms","start":"2026-09-19T11:26:17.026896Z","end":"2026-09-19T11:26:17.207523Z","steps":["trace[2010036242] 'read index received'  (duration: 155.824226ms)","trace[2010036242] 'applied index is now lower than readState.Index'  (duration: 24.799883ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:26:17.207597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:16.888766Z","time spent":"318.720409ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:26167 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-09-19T11:26:17.207609Z","caller":"traceutil/trace.go:171","msg":"trace[1678820117] transaction","detail":"{read_only:false; response_revision:26182; number_of_response:1; }","duration":"317.830549ms","start":"2026-09-19T11:26:16.889718Z","end":"2026-09-19T11:26:17.207548Z","steps":["trace[1678820117] 'process raft request'  (duration: 317.547113ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:17.207821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:16.889695Z","time spent":"318.012534ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:26165 > 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":"warn","ts":"2026-09-19T11:26:17.275823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.858ms","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-09-19T11:26:17.275912Z","caller":"traceutil/trace.go:171","msg":"trace[1895723470] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:26182; }","duration":"249.047355ms","start":"2026-09-19T11:26:17.026836Z","end":"2026-09-19T11:26:17.275883Z","steps":["trace[1895723470] 'agreement among raft nodes before linearized reading'  (duration: 180.824073ms)","trace[1895723470] 'count revisions from in-memory index tree'  (duration: 68.041057ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:18.055639Z","caller":"traceutil/trace.go:171","msg":"trace[1643739942] linearizableReadLoop","detail":"{readStateIndex:27057; appliedIndex:27056; }","duration":"161.952952ms","start":"2026-09-19T11:26:17.893662Z","end":"2026-09-19T11:26:18.055615Z","steps":["trace[1643739942] 'read index received'  (duration: 83.286777ms)","trace[1643739942] 'applied index is now lower than readState.Index'  (duration: 78.665265ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:18.055662Z","caller":"traceutil/trace.go:171","msg":"trace[1480112605] transaction","detail":"{read_only:false; response_revision:26186; number_of_response:1; }","duration":"195.228075ms","start":"2026-09-19T11:26:17.860392Z","end":"2026-09-19T11:26:18.05562Z","steps":["trace[1480112605] 'process raft request'  (duration: 116.53898ms)","trace[1480112605] 'compare'  (duration: 78.513121ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:18.055829Z","caller":"traceutil/trace.go:171","msg":"trace[2141759779] transaction","detail":"{read_only:false; response_revision:26187; number_of_response:1; }","duration":"156.098352ms","start":"2026-09-19T11:26:17.899719Z","end":"2026-09-19T11:26:18.055817Z","steps":["trace[2141759779] 'process raft request'  (duration: 155.851307ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:18.055938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.600628ms","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-09-19T11:26:18.056599Z","caller":"traceutil/trace.go:171","msg":"trace[761023077] 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:26187; }","duration":"198.327954ms","start":"2026-09-19T11:26:17.858245Z","end":"2026-09-19T11:26:18.056573Z","steps":["trace[761023077] 'agreement among raft nodes before linearized reading'  (duration: 197.510876ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:18.404984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.571331ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5320898202178513102 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/capi-system/controller-leader-election-capi\" mod_revision:26174 > 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\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-19T11:26:18.405113Z","caller":"traceutil/trace.go:171","msg":"trace[161320829] linearizableReadLoop","detail":"{readStateIndex:27059; appliedIndex:27058; }","duration":"114.868532ms","start":"2026-09-19T11:26:18.290224Z","end":"2026-09-19T11:26:18.405092Z","steps":["trace[161320829] 'read index received'  (duration: 64.671µs)","trace[161320829] 'applied index is now lower than readState.Index'  (duration: 114.80185ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:26:18.405253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.034986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-09-19T11:26:18.405293Z","caller":"traceutil/trace.go:171","msg":"trace[1311150493] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:26188; }","duration":"115.118408ms","start":"2026-09-19T11:26:18.290158Z","end":"2026-09-19T11:26:18.405277Z","steps":["trace[1311150493] 'agreement among raft nodes before linearized reading'  (duration: 114.987585ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:18.405737Z","caller":"traceutil/trace.go:171","msg":"trace[2116518598] transaction","detail":"{read_only:false; response_revision:26188; number_of_response:1; }","duration":"271.313251ms","start":"2026-09-19T11:26:18.13437Z","end":"2026-09-19T11:26:18.405683Z","steps":["trace[2116518598] 'process raft request'  (duration: 109.759928ms)","trace[2116518598] 'compare'  (duration: 160.434758ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:18.41272Z","caller":"traceutil/trace.go:171","msg":"trace[678326805] transaction","detail":"{read_only:false; response_revision:26189; number_of_response:1; }","duration":"108.95892ms","start":"2026-09-19T11:26:18.303725Z","end":"2026-09-19T11:26:18.412684Z","steps":["trace[678326805] 'process raft request'  (duration: 108.200263ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:18.544154Z","caller":"traceutil/trace.go:171","msg":"trace[2098786738] transaction","detail":"{read_only:false; response_revision:26190; number_of_response:1; }","duration":"234.686424ms","start":"2026-09-19T11:26:18.309418Z","end":"2026-09-19T11:26:18.544104Z","steps":["trace[2098786738] 'process raft request'  (duration: 232.681719ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:19.041227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.572191ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5320898202178513111 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" mod_revision:26132 > success:<request_put:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" value_size:416 >> failure:<request_range:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-19T11:26:19.041397Z","caller":"traceutil/trace.go:171","msg":"trace[544172906] transaction","detail":"{read_only:false; response_revision:26192; number_of_response:1; }","duration":"348.857601ms","start":"2026-09-19T11:26:18.692511Z","end":"2026-09-19T11:26:19.041368Z","steps":["trace[544172906] 'process raft request'  (duration: 236.034204ms)","trace[544172906] 'compare'  (duration: 112.205243ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:19.041515Z","caller":"traceutil/trace.go:171","msg":"trace[1674275490] transaction","detail":"{read_only:false; response_revision:26194; number_of_response:1; }","duration":"151.48075ms","start":"2026-09-19T11:26:18.890012Z","end":"2026-09-19T11:26:19.041493Z","steps":["trace[1674275490] 'process raft request'  (duration: 151.434299ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:19.041551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:18.692497Z","time spent":"348.966163ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" mod_revision:26132 > success:<request_put:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" value_size:416 >> failure:<request_range:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" > >"}
{"level":"info","ts":"2026-09-19T11:26:19.04171Z","caller":"traceutil/trace.go:171","msg":"trace[756579689] linearizableReadLoop","detail":"{readStateIndex:27063; appliedIndex:27062; }","duration":"258.464305ms","start":"2026-09-19T11:26:18.783228Z","end":"2026-09-19T11:26:19.041693Z","steps":["trace[756579689] 'read index received'  (duration: 145.28171ms)","trace[756579689] 'applied index is now lower than readState.Index'  (duration: 113.181445ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:19.041933Z","caller":"traceutil/trace.go:171","msg":"trace[1901925491] transaction","detail":"{read_only:false; response_revision:26193; number_of_response:1; }","duration":"153.549114ms","start":"2026-09-19T11:26:18.888373Z","end":"2026-09-19T11:26:19.041922Z","steps":["trace[1901925491] 'process raft request'  (duration: 153.005502ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:19.04223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.004917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:26:19.04226Z","caller":"traceutil/trace.go:171","msg":"trace[286713144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26194; }","duration":"259.073449ms","start":"2026-09-19T11:26:18.783177Z","end":"2026-09-19T11:26:19.04225Z","steps":["trace[286713144] 'agreement among raft nodes before linearized reading'  (duration: 259.014327ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:19.042134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.529223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"}
{"level":"info","ts":"2026-09-19T11:26:19.04243Z","caller":"traceutil/trace.go:171","msg":"trace[127649451] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26194; }","duration":"209.86181ms","start":"2026-09-19T11:26:18.832552Z","end":"2026-09-19T11:26:19.042414Z","steps":["trace[127649451] 'agreement among raft nodes before linearized reading'  (duration: 209.449891ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:19.383091Z","caller":"traceutil/trace.go:171","msg":"trace[670464122] transaction","detail":"{read_only:false; response_revision:26196; number_of_response:1; }","duration":"123.899533ms","start":"2026-09-19T11:26:19.259153Z","end":"2026-09-19T11:26:19.383053Z","steps":["trace[670464122] 'process raft request'  (duration: 123.700049ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:19.636893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.69704ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5320898202178513122 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:26182 > 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\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-19T11:26:19.637164Z","caller":"traceutil/trace.go:171","msg":"trace[325052396] transaction","detail":"{read_only:false; response_revision:26198; number_of_response:1; }","duration":"253.418931ms","start":"2026-09-19T11:26:19.383723Z","end":"2026-09-19T11:26:19.637142Z","steps":["trace[325052396] 'process raft request'  (duration: 253.290458ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:19.637424Z","caller":"traceutil/trace.go:171","msg":"trace[151276136] transaction","detail":"{read_only:false; response_revision:26197; number_of_response:1; }","duration":"378.201224ms","start":"2026-09-19T11:26:19.259182Z","end":"2026-09-19T11:26:19.637384Z","steps":["trace[151276136] 'process raft request'  (duration: 252.904981ms)","trace[151276136] 'compare'  (duration: 124.544307ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:26:19.637647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:19.259164Z","time spent":"378.354508ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:26182 > 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-09-19T11:26:19.907355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.995675ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5320898202178513124 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:26183 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-19T11:26:19.907531Z","caller":"traceutil/trace.go:171","msg":"trace[413377092] linearizableReadLoop","detail":"{readStateIndex:27071; appliedIndex:27069; }","duration":"207.310844ms","start":"2026-09-19T11:26:19.7002Z","end":"2026-09-19T11:26:19.907511Z","steps":["trace[413377092] 'read index received'  (duration: 70.171325ms)","trace[413377092] 'applied index is now lower than readState.Index'  (duration: 137.138149ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:19.90758Z","caller":"traceutil/trace.go:171","msg":"trace[1437395387] transaction","detail":"{read_only:false; response_revision:26199; number_of_response:1; }","duration":"244.728768ms","start":"2026-09-19T11:26:19.662813Z","end":"2026-09-19T11:26:19.907542Z","steps":["trace[1437395387] 'process raft request'  (duration: 107.422746ms)","trace[1437395387] 'compare'  (duration: 136.848512ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:26:19.907647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.450966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:26:19.907609Z","caller":"traceutil/trace.go:171","msg":"trace[2123839000] transaction","detail":"{read_only:false; response_revision:26200; number_of_response:1; }","duration":"240.612026ms","start":"2026-09-19T11:26:19.66696Z","end":"2026-09-19T11:26:19.907572Z","steps":["trace[2123839000] 'process raft request'  (duration: 240.493413ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:19.907682Z","caller":"traceutil/trace.go:171","msg":"trace[1044950663] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:26200; }","duration":"207.526438ms","start":"2026-09-19T11:26:19.70014Z","end":"2026-09-19T11:26:19.907667Z","steps":["trace[1044950663] 'agreement among raft nodes before linearized reading'  (duration: 207.463147ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:19.90787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.601957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:26:19.907965Z","caller":"traceutil/trace.go:171","msg":"trace[1966706315] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26200; }","duration":"123.76493ms","start":"2026-09-19T11:26:19.784173Z","end":"2026-09-19T11:26:19.907938Z","steps":["trace[1966706315] 'agreement among raft nodes before linearized reading'  (duration: 123.603627ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:19.908121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.39285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:26:19.908152Z","caller":"traceutil/trace.go:171","msg":"trace[1463665246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26200; }","duration":"122.473062ms","start":"2026-09-19T11:26:19.785669Z","end":"2026-09-19T11:26:19.908142Z","steps":["trace[1463665246] 'agreement among raft nodes before linearized reading'  (duration: 122.41777ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:20.182893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.892742ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5320898202178513129 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:26185 > 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-09-19T11:26:20.183018Z","caller":"traceutil/trace.go:171","msg":"trace[54096053] linearizableReadLoop","detail":"{readStateIndex:27072; appliedIndex:27071; }","duration":"268.261354ms","start":"2026-09-19T11:26:19.914738Z","end":"2026-09-19T11:26:20.182999Z","steps":["trace[54096053] 'read index received'  (duration: 149.040824ms)","trace[54096053] 'applied index is now lower than readState.Index'  (duration: 119.21912ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:26:20.183154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.215907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:26:20.183111Z","caller":"traceutil/trace.go:171","msg":"trace[230944068] transaction","detail":"{read_only:false; response_revision:26201; number_of_response:1; }","duration":"270.008992ms","start":"2026-09-19T11:26:19.913052Z","end":"2026-09-19T11:26:20.183061Z","steps":["trace[230944068] 'process raft request'  (duration: 150.714251ms)","trace[230944068] 'compare'  (duration: 118.769119ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:20.183185Z","caller":"traceutil/trace.go:171","msg":"trace[1476670266] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:26201; }","duration":"179.294688ms","start":"2026-09-19T11:26:20.003878Z","end":"2026-09-19T11:26:20.183172Z","steps":["trace[1476670266] 'agreement among raft nodes before linearized reading'  (duration: 179.220077ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:20.183378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.562902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"}
{"level":"info","ts":"2026-09-19T11:26:20.18346Z","caller":"traceutil/trace.go:171","msg":"trace[954589675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26201; }","duration":"113.706325ms","start":"2026-09-19T11:26:20.069734Z","end":"2026-09-19T11:26:20.18344Z","steps":["trace[954589675] 'agreement among raft nodes before linearized reading'  (duration: 113.427819ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:20.183591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.748914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:26:20.183716Z","caller":"traceutil/trace.go:171","msg":"trace[1689332316] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26201; }","duration":"268.985739ms","start":"2026-09-19T11:26:19.914705Z","end":"2026-09-19T11:26:20.18369Z","steps":["trace[1689332316] 'agreement among raft nodes before linearized reading'  (duration: 268.371145ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:20.429694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.625608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"}
{"level":"info","ts":"2026-09-19T11:26:20.429783Z","caller":"traceutil/trace.go:171","msg":"trace[1823678258] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26202; }","duration":"115.783762ms","start":"2026-09-19T11:26:20.313978Z","end":"2026-09-19T11:26:20.429762Z","steps":["trace[1823678258] 'range keys from in-memory index tree'  (duration: 115.361783ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:20.518996Z","caller":"traceutil/trace.go:171","msg":"trace[2137049281] transaction","detail":"{read_only:false; response_revision:26204; number_of_response:1; }","duration":"101.233468ms","start":"2026-09-19T11:26:20.417721Z","end":"2026-09-19T11:26:20.518954Z","steps":["trace[2137049281] 'process raft request'  (duration: 100.957952ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:20.690273Z","caller":"traceutil/trace.go:171","msg":"trace[1625353696] transaction","detail":"{read_only:false; response_revision:26205; number_of_response:1; }","duration":"139.850779ms","start":"2026-09-19T11:26:20.550312Z","end":"2026-09-19T11:26:20.690163Z","steps":["trace[1625353696] 'process raft request'  (duration: 124.510047ms)","trace[1625353696] 'compare'  (duration: 15.173958ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:20.938724Z","caller":"traceutil/trace.go:171","msg":"trace[1641633651] linearizableReadLoop","detail":"{readStateIndex:27077; appliedIndex:27076; }","duration":"200.120374ms","start":"2026-09-19T11:26:20.738557Z","end":"2026-09-19T11:26:20.938677Z","steps":["trace[1641633651] 'read index received'  (duration: 110.389943ms)","trace[1641633651] 'applied index is now lower than readState.Index'  (duration: 89.728851ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:26:20.939114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.522263ms","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-09-19T11:26:20.939137Z","caller":"traceutil/trace.go:171","msg":"trace[249944760] transaction","detail":"{read_only:false; response_revision:26206; number_of_response:1; }","duration":"203.812686ms","start":"2026-09-19T11:26:20.735227Z","end":"2026-09-19T11:26:20.93904Z","steps":["trace[249944760] 'process raft request'  (duration: 113.793938ms)","trace[249944760] 'compare'  (duration: 89.445465ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:20.939188Z","caller":"traceutil/trace.go:171","msg":"trace[532397377] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26206; }","duration":"200.664456ms","start":"2026-09-19T11:26:20.738503Z","end":"2026-09-19T11:26:20.939168Z","steps":["trace[532397377] 'agreement among raft nodes before linearized reading'  (duration: 200.330118ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:21.446889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.401061ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5320898202178513150 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:26193 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:476 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-19T11:26:21.44702Z","caller":"traceutil/trace.go:171","msg":"trace[1307867002] linearizableReadLoop","detail":"{readStateIndex:27079; appliedIndex:27077; }","duration":"381.876817ms","start":"2026-09-19T11:26:21.065119Z","end":"2026-09-19T11:26:21.446996Z","steps":["trace[1307867002] 'read index received'  (duration: 31.276517ms)","trace[1307867002] 'applied index is now lower than readState.Index'  (duration: 350.59829ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:21.447129Z","caller":"traceutil/trace.go:171","msg":"trace[1992218760] transaction","detail":"{read_only:false; response_revision:26208; number_of_response:1; }","duration":"394.519929ms","start":"2026-09-19T11:26:21.052595Z","end":"2026-09-19T11:26:21.447115Z","steps":["trace[1992218760] 'process raft request'  (duration: 244.76514ms)","trace[1992218760] 'compare'  (duration: 149.141646ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:26:21.447197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:21.052559Z","time spent":"394.603851ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:26193 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:476 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-19T11:26:21.447579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.117063ms","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-09-19T11:26:21.447678Z","caller":"traceutil/trace.go:171","msg":"trace[610084349] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26208; }","duration":"339.351738ms","start":"2026-09-19T11:26:21.108298Z","end":"2026-09-19T11:26:21.44765Z","steps":["trace[610084349] 'agreement among raft nodes before linearized reading'  (duration: 339.059442ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:21.447726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:21.108279Z","time spent":"339.437592ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"warn","ts":"2026-09-19T11:26:21.447591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.466169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-09-19T11:26:21.447833Z","caller":"traceutil/trace.go:171","msg":"trace[1508284924] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:26208; }","duration":"382.699935ms","start":"2026-09-19T11:26:21.065098Z","end":"2026-09-19T11:26:21.447798Z","steps":["trace[1508284924] 'agreement among raft nodes before linearized reading'  (duration: 382.387247ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:21.447929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:21.065007Z","time spent":"382.880829ms","remote":"127.0.0.1:54052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":32,"response size":32,"request content":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true "}
{"level":"info","ts":"2026-09-19T11:26:21.555379Z","caller":"traceutil/trace.go:171","msg":"trace[1454464612] transaction","detail":"{read_only:false; response_revision:26210; number_of_response:1; }","duration":"102.089937ms","start":"2026-09-19T11:26:21.453258Z","end":"2026-09-19T11:26:21.555348Z","steps":["trace[1454464612] 'process raft request'  (duration: 101.911824ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:21.555593Z","caller":"traceutil/trace.go:171","msg":"trace[1531281018] transaction","detail":"{read_only:false; response_revision:26209; number_of_response:1; }","duration":"102.297382ms","start":"2026-09-19T11:26:21.453258Z","end":"2026-09-19T11:26:21.555556Z","steps":["trace[1531281018] 'process raft request'  (duration: 100.813328ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:25.476355Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069e6.wal"}
{"level":"info","ts":"2026-09-19T11:26:33.815504Z","caller":"traceutil/trace.go:171","msg":"trace[47731853] linearizableReadLoop","detail":"{readStateIndex:27170; appliedIndex:27169; }","duration":"201.77516ms","start":"2026-09-19T11:26:33.613693Z","end":"2026-09-19T11:26:33.815468Z","steps":["trace[47731853] 'read index received'  (duration: 201.461423ms)","trace[47731853] 'applied index is now lower than readState.Index'  (duration: 312.257µs)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:33.815582Z","caller":"traceutil/trace.go:171","msg":"trace[1368640488] transaction","detail":"{read_only:false; response_revision:26296; number_of_response:1; }","duration":"324.568128ms","start":"2026-09-19T11:26:33.490964Z","end":"2026-09-19T11:26:33.815533Z","steps":["trace[1368640488] 'process raft request'  (duration: 324.286912ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:33.815795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:33.490927Z","time spent":"324.752242ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:26282 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-19T11:26:33.815829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.965507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"info","ts":"2026-09-19T11:26:33.815883Z","caller":"traceutil/trace.go:171","msg":"trace[1654515944] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26296; }","duration":"199.059871ms","start":"2026-09-19T11:26:33.616802Z","end":"2026-09-19T11:26:33.815862Z","steps":["trace[1654515944] 'agreement among raft nodes before linearized reading'  (duration: 198.868956ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:33.815944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.205451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"}
{"level":"info","ts":"2026-09-19T11:26:33.81606Z","caller":"traceutil/trace.go:171","msg":"trace[796332147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26296; }","duration":"202.384625ms","start":"2026-09-19T11:26:33.613645Z","end":"2026-09-19T11:26:33.816029Z","steps":["trace[796332147] 'agreement among raft nodes before linearized reading'  (duration: 201.981345ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:33.816293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.594356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-09-19T11:26:33.816412Z","caller":"traceutil/trace.go:171","msg":"trace[650223886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26296; }","duration":"101.802542ms","start":"2026-09-19T11:26:33.714584Z","end":"2026-09-19T11:26:33.816386Z","steps":["trace[650223886] 'agreement among raft nodes before linearized reading'  (duration: 101.413303ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:34.184785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.062975ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5320898202178513367 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:26283 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-19T11:26:34.184923Z","caller":"traceutil/trace.go:171","msg":"trace[32308052] transaction","detail":"{read_only:false; response_revision:26297; number_of_response:1; }","duration":"363.20341ms","start":"2026-09-19T11:26:33.821695Z","end":"2026-09-19T11:26:34.184898Z","steps":["trace[32308052] 'process raft request'  (duration: 126.91843ms)","trace[32308052] 'compare'  (duration: 235.316329ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:26:34.184998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:33.821673Z","time spent":"363.292821ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:26283 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-09-19T11:26:34.207654Z","caller":"traceutil/trace.go:171","msg":"trace[581622529] linearizableReadLoop","detail":"{readStateIndex:27173; appliedIndex:27170; }","duration":"381.968789ms","start":"2026-09-19T11:26:33.825655Z","end":"2026-09-19T11:26:34.207624Z","steps":["trace[581622529] 'read index received'  (duration: 122.951142ms)","trace[581622529] 'applied index is now lower than readState.Index'  (duration: 259.016217ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:26:34.207841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.160573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:26:34.207887Z","caller":"traceutil/trace.go:171","msg":"trace[65483085] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:26299; }","duration":"382.251065ms","start":"2026-09-19T11:26:33.825619Z","end":"2026-09-19T11:26:34.20787Z","steps":["trace[65483085] 'agreement among raft nodes before linearized reading'  (duration: 382.106382ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:34.207923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:33.825559Z","time spent":"382.354307ms","remote":"127.0.0.1:50220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":30,"request content":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" "}
{"level":"info","ts":"2026-09-19T11:26:34.207876Z","caller":"traceutil/trace.go:171","msg":"trace[999607934] transaction","detail":"{read_only:false; response_revision:26299; number_of_response:1; }","duration":"385.690331ms","start":"2026-09-19T11:26:33.822123Z","end":"2026-09-19T11:26:34.207813Z","steps":["trace[999607934] 'process raft request'  (duration: 385.447125ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:34.207692Z","caller":"traceutil/trace.go:171","msg":"trace[801539025] transaction","detail":"{read_only:false; response_revision:26298; number_of_response:1; }","duration":"385.849584ms","start":"2026-09-19T11:26:33.821807Z","end":"2026-09-19T11:26:34.207657Z","steps":["trace[801539025] 'process raft request'  (duration: 385.556438ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:34.208986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:33.822103Z","time spent":"386.802207ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:26285 > 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":"warn","ts":"2026-09-19T11:26:34.209082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:33.821777Z","time spent":"387.239886ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:26284 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:463 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"info","ts":"2026-09-19T11:26:34.315116Z","caller":"traceutil/trace.go:171","msg":"trace[1716714438] transaction","detail":"{read_only:false; response_revision:26300; number_of_response:1; }","duration":"353.853093ms","start":"2026-09-19T11:26:33.961229Z","end":"2026-09-19T11:26:34.315082Z","steps":["trace[1716714438] 'process raft request'  (duration: 328.715052ms)","trace[1716714438] 'compare'  (duration: 24.876615ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:26:34.315331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:33.961202Z","time spent":"354.007856ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:26287 > success:<request_put:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" > >"}
{"level":"info","ts":"2026-09-19T11:26:34.31544Z","caller":"traceutil/trace.go:171","msg":"trace[239528497] transaction","detail":"{read_only:false; response_revision:26301; number_of_response:1; }","duration":"351.093211ms","start":"2026-09-19T11:26:33.964299Z","end":"2026-09-19T11:26:34.315392Z","steps":["trace[239528497] 'process raft request'  (duration: 350.716732ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:34.315651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:33.964268Z","time spent":"351.273324ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:26288 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-09-19T11:26:34.315689Z","caller":"traceutil/trace.go:171","msg":"trace[707028264] transaction","detail":"{read_only:false; response_revision:26303; number_of_response:1; }","duration":"102.372453ms","start":"2026-09-19T11:26:34.213266Z","end":"2026-09-19T11:26:34.315638Z","steps":["trace[707028264] 'process raft request'  (duration: 102.170559ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:34.315725Z","caller":"traceutil/trace.go:171","msg":"trace[480744835] transaction","detail":"{read_only:false; response_revision:26302; number_of_response:1; }","duration":"259.877816ms","start":"2026-09-19T11:26:34.055806Z","end":"2026-09-19T11:26:34.315684Z","steps":["trace[480744835] 'process raft request'  (duration: 259.458057ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:34.606321Z","caller":"traceutil/trace.go:171","msg":"trace[1779816416] transaction","detail":"{read_only:false; response_revision:26304; number_of_response:1; }","duration":"284.20393ms","start":"2026-09-19T11:26:34.322072Z","end":"2026-09-19T11:26:34.606275Z","steps":["trace[1779816416] 'process raft request'  (duration: 252.632815ms)","trace[1779816416] 'compare'  (duration: 30.619353ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:34.606498Z","caller":"traceutil/trace.go:171","msg":"trace[554698039] linearizableReadLoop","detail":"{readStateIndex:27178; appliedIndex:27177; }","duration":"283.78568ms","start":"2026-09-19T11:26:34.322691Z","end":"2026-09-19T11:26:34.606476Z","steps":["trace[554698039] 'read index received'  (duration: 251.9965ms)","trace[554698039] 'applied index is now lower than readState.Index'  (duration: 31.78736ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:26:34.606775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.052966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1578"}
{"level":"info","ts":"2026-09-19T11:26:34.60688Z","caller":"traceutil/trace.go:171","msg":"trace[775374211] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26305; }","duration":"284.209359ms","start":"2026-09-19T11:26:34.322646Z","end":"2026-09-19T11:26:34.606856Z","steps":["trace[775374211] 'agreement among raft nodes before linearized reading'  (duration: 283.920763ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:35.163531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.276634ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5320898202178513378 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:26292 > 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-09-19T11:26:35.163788Z","caller":"traceutil/trace.go:171","msg":"trace[1574384753] linearizableReadLoop","detail":"{readStateIndex:27180; appliedIndex:27179; }","duration":"557.190277ms","start":"2026-09-19T11:26:34.606546Z","end":"2026-09-19T11:26:35.163736Z","steps":["trace[1574384753] 'read index received'  (duration: 327.744709ms)","trace[1574384753] 'applied index is now lower than readState.Index'  (duration: 229.443878ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:35.163957Z","caller":"traceutil/trace.go:171","msg":"trace[1343702285] transaction","detail":"{read_only:false; response_revision:26306; number_of_response:1; }","duration":"586.386959ms","start":"2026-09-19T11:26:34.57754Z","end":"2026-09-19T11:26:35.163927Z","steps":["trace[1343702285] 'process raft request'  (duration: 356.585514ms)","trace[1343702285] 'compare'  (duration: 228.898114ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:26:35.164129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.346177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"warn","ts":"2026-09-19T11:26:35.164163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.815731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1578"}
{"level":"info","ts":"2026-09-19T11:26:35.164262Z","caller":"traceutil/trace.go:171","msg":"trace[2026786002] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26306; }","duration":"839.975035ms","start":"2026-09-19T11:26:34.324261Z","end":"2026-09-19T11:26:35.164236Z","steps":["trace[2026786002] 'agreement among raft nodes before linearized reading'  (duration: 839.647558ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:35.164142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:34.577511Z","time spent":"586.528322ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:26292 > 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\" > >"}
{"level":"warn","ts":"2026-09-19T11:26:35.164114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.695643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"}
{"level":"info","ts":"2026-09-19T11:26:35.164375Z","caller":"traceutil/trace.go:171","msg":"trace[879101794] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26306; }","duration":"716.522541ms","start":"2026-09-19T11:26:34.44769Z","end":"2026-09-19T11:26:35.164213Z","steps":["trace[879101794] 'agreement among raft nodes before linearized reading'  (duration: 716.170893ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:35.164423Z","caller":"traceutil/trace.go:171","msg":"trace[745270579] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:26306; }","duration":"638.02509ms","start":"2026-09-19T11:26:34.526375Z","end":"2026-09-19T11:26:35.1644Z","steps":["trace[745270579] 'agreement among raft nodes before linearized reading'  (duration: 637.483368ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:35.164465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:34.447676Z","time spent":"716.738326ms","remote":"127.0.0.1:39892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "}
{"level":"warn","ts":"2026-09-19T11:26:35.164564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:34.324243Z","time spent":"840.063696ms","remote":"127.0.0.1:39848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1602,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "}
{"level":"warn","ts":"2026-09-19T11:26:35.164476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:34.526332Z","time spent":"638.121862ms","remote":"127.0.0.1:48964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1960,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "}
{"level":"warn","ts":"2026-09-19T11:26:35.164153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.342755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"}
{"level":"info","ts":"2026-09-19T11:26:35.164768Z","caller":"traceutil/trace.go:171","msg":"trace[1007484164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26306; }","duration":"828.971079ms","start":"2026-09-19T11:26:34.335779Z","end":"2026-09-19T11:26:35.16475Z","steps":["trace[1007484164] 'agreement among raft nodes before linearized reading'  (duration: 828.11358ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:35.164805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:34.335767Z","time spent":"829.02635ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-09-19T11:26:35.176239Z","caller":"traceutil/trace.go:171","msg":"trace[150339911] transaction","detail":"{read_only:false; response_revision:26307; number_of_response:1; }","duration":"513.451851ms","start":"2026-09-19T11:26:34.662762Z","end":"2026-09-19T11:26:35.176214Z","steps":["trace[150339911] 'process raft request'  (duration: 513.264797ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:35.176391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:34.662733Z","time spent":"513.561264ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/capi-kubeadm-control-plane-system/kubeadm-control-plane-manager-leader-election-capi\" mod_revision:26271 > success:<request_put:<key:\"/registry/leases/capi-kubeadm-control-plane-system/kubeadm-control-plane-manager-leader-election-capi\" value_size:518 >> failure:<request_range:<key:\"/registry/leases/capi-kubeadm-control-plane-system/kubeadm-control-plane-manager-leader-election-capi\" > >"}
{"level":"warn","ts":"2026-09-19T11:26:35.176541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.998132ms","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":"warn","ts":"2026-09-19T11:26:35.176572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.665189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:26:35.176609Z","caller":"traceutil/trace.go:171","msg":"trace[1057095687] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26308; }","duration":"166.086864ms","start":"2026-09-19T11:26:35.010503Z","end":"2026-09-19T11:26:35.17659Z","steps":["trace[1057095687] 'agreement among raft nodes before linearized reading'  (duration: 165.90385ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:35.176624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.092555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-09-19T11:26:35.176679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.965074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:26:35.176681Z","caller":"traceutil/trace.go:171","msg":"trace[474703555] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:26308; }","duration":"227.192407ms","start":"2026-09-19T11:26:34.949466Z","end":"2026-09-19T11:26:35.176659Z","steps":["trace[474703555] 'agreement among raft nodes before linearized reading'  (duration: 227.120336ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:35.176711Z","caller":"traceutil/trace.go:171","msg":"trace[2048097173] transaction","detail":"{read_only:false; response_revision:26308; number_of_response:1; }","duration":"427.274589ms","start":"2026-09-19T11:26:34.749425Z","end":"2026-09-19T11:26:35.1767Z","steps":["trace[2048097173] 'process raft request'  (duration: 426.737017ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:35.176755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.905711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-09-19T11:26:35.176775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:34.749405Z","time spent":"427.33066ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" mod_revision:26293 > success:<request_put:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" value_size:507 >> failure:<request_range:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" > >"}
{"level":"info","ts":"2026-09-19T11:26:35.176785Z","caller":"traceutil/trace.go:171","msg":"trace[1581992659] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:26308; }","duration":"401.001224ms","start":"2026-09-19T11:26:34.775776Z","end":"2026-09-19T11:26:35.176777Z","steps":["trace[1581992659] 'agreement among raft nodes before linearized reading'  (duration: 400.955293ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:35.176531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.449028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-09-19T11:26:35.176829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:34.775761Z","time spent":"401.054645ms","remote":"127.0.0.1:47700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true "}
{"level":"info","ts":"2026-09-19T11:26:35.176865Z","caller":"traceutil/trace.go:171","msg":"trace[1397081000] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:26308; }","duration":"286.876407ms","start":"2026-09-19T11:26:34.889967Z","end":"2026-09-19T11:26:35.176843Z","steps":["trace[1397081000] 'agreement among raft nodes before linearized reading'  (duration: 286.422567ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:35.176629Z","caller":"traceutil/trace.go:171","msg":"trace[1936285811] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:26308; }","duration":"336.74997ms","start":"2026-09-19T11:26:34.839862Z","end":"2026-09-19T11:26:35.176611Z","steps":["trace[1936285811] 'agreement among raft nodes before linearized reading'  (duration: 336.655538ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:35.176716Z","caller":"traceutil/trace.go:171","msg":"trace[1295761932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26308; }","duration":"393.041256ms","start":"2026-09-19T11:26:34.783665Z","end":"2026-09-19T11:26:35.176707Z","steps":["trace[1295761932] 'agreement among raft nodes before linearized reading'  (duration: 392.984455ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:35.176973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:34.83985Z","time spent":"337.106448ms","remote":"127.0.0.1:47688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true "}
{"level":"warn","ts":"2026-09-19T11:26:35.176997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:34.78365Z","time spent":"393.338172ms","remote":"127.0.0.1:50150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-09-19T11:26:35.605179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.310914ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5320898202178513383 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:26295 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:458 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-19T11:26:35.605551Z","caller":"traceutil/trace.go:171","msg":"trace[1560360817] transaction","detail":"{read_only:false; response_revision:26310; number_of_response:1; }","duration":"433.917637ms","start":"2026-09-19T11:26:35.171611Z","end":"2026-09-19T11:26:35.605528Z","steps":["trace[1560360817] 'process raft request'  (duration: 433.699572ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:35.605551Z","caller":"traceutil/trace.go:171","msg":"trace[1237139377] transaction","detail":"{read_only:false; response_revision:26309; number_of_response:1; }","duration":"434.239624ms","start":"2026-09-19T11:26:35.171252Z","end":"2026-09-19T11:26:35.605492Z","steps":["trace[1237139377] 'process raft request'  (duration: 122.486482ms)","trace[1237139377] 'compare'  (duration: 310.72926ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:26:35.605649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:35.171585Z","time spent":"434.0327ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:26290 > 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-09-19T11:26:35.605785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:35.171224Z","time spent":"434.435559ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:26295 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:458 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"info","ts":"2026-09-19T11:26:35.606096Z","caller":"traceutil/trace.go:171","msg":"trace[801478653] transaction","detail":"{read_only:false; response_revision:26311; number_of_response:1; }","duration":"429.870698ms","start":"2026-09-19T11:26:35.17618Z","end":"2026-09-19T11:26:35.606051Z","steps":["trace[801478653] 'process raft request'  (duration: 429.273124ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:35.60609Z","caller":"traceutil/trace.go:171","msg":"trace[2074526275] linearizableReadLoop","detail":"{readStateIndex:27185; appliedIndex:27182; }","duration":"429.698074ms","start":"2026-09-19T11:26:35.176358Z","end":"2026-09-19T11:26:35.606056Z","steps":["trace[2074526275] 'read index received'  (duration: 117.552961ms)","trace[2074526275] 'applied index is now lower than readState.Index'  (duration: 312.143903ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:26:35.606199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:35.17616Z","time spent":"429.985461ms","remote":"127.0.0.1:39848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1873,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" mod_revision:26303 > success:<request_put:<key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" value_size:1800 >> failure:<request_range:<key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" > >"}
{"level":"info","ts":"2026-09-19T11:26:35.606169Z","caller":"traceutil/trace.go:171","msg":"trace[1612934669] transaction","detail":"{read_only:false; response_revision:26312; number_of_response:1; }","duration":"424.235313ms","start":"2026-09-19T11:26:35.181899Z","end":"2026-09-19T11:26:35.606134Z","steps":["trace[1612934669] 'process raft request'  (duration: 423.995107ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:35.606552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:35.181886Z","time spent":"424.501598ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:26294 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:409 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-09-19T11:26:35.606577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.052928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-14772336-ccb2-4a25-a040-1ef4047a0908\" ","response":"range_response_count:1 size:1451"}
{"level":"warn","ts":"2026-09-19T11:26:35.606558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.208001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:26:35.606654Z","caller":"traceutil/trace.go:171","msg":"trace[1587822537] range","detail":"{range_begin:/registry/persistentvolumes/pvc-14772336-ccb2-4a25-a040-1ef4047a0908; range_end:; response_count:1; response_revision:26312; }","duration":"437.237522ms","start":"2026-09-19T11:26:35.169399Z","end":"2026-09-19T11:26:35.606637Z","steps":["trace[1587822537] 'agreement among raft nodes before linearized reading'  (duration: 436.905944ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:35.606667Z","caller":"traceutil/trace.go:171","msg":"trace[659205676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26312; }","duration":"428.366114ms","start":"2026-09-19T11:26:35.178276Z","end":"2026-09-19T11:26:35.606642Z","steps":["trace[659205676] 'agreement among raft nodes before linearized reading'  (duration: 428.168569ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:35.606722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:35.178261Z","time spent":"428.451386ms","remote":"127.0.0.1:50148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-09-19T11:26:35.606741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:35.16937Z","time spent":"437.356565ms","remote":"127.0.0.1:48948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1475,"request content":"key:\"/registry/persistentvolumes/pvc-14772336-ccb2-4a25-a040-1ef4047a0908\" "}
{"level":"info","ts":"2026-09-19T11:26:35.920922Z","caller":"traceutil/trace.go:171","msg":"trace[156396397] linearizableReadLoop","detail":"{readStateIndex:27187; appliedIndex:27186; }","duration":"281.776115ms","start":"2026-09-19T11:26:35.639084Z","end":"2026-09-19T11:26:35.92086Z","steps":["trace[156396397] 'read index received'  (duration: 281.649132ms)","trace[156396397] 'applied index is now lower than readState.Index'  (duration: 125.242µs)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:35.920957Z","caller":"traceutil/trace.go:171","msg":"trace[250295370] transaction","detail":"{read_only:false; response_revision:26313; number_of_response:1; }","duration":"304.88375ms","start":"2026-09-19T11:26:35.616036Z","end":"2026-09-19T11:26:35.92092Z","steps":["trace[250295370] 'process raft request'  (duration: 304.574173ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:35.921155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.042891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-09-19T11:26:35.921219Z","caller":"traceutil/trace.go:171","msg":"trace[429108898] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:26313; }","duration":"282.188944ms","start":"2026-09-19T11:26:35.639019Z","end":"2026-09-19T11:26:35.921208Z","steps":["trace[429108898] 'agreement among raft nodes before linearized reading'  (duration: 282.01205ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:35.921207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:35.616002Z","time spent":"305.090695ms","remote":"127.0.0.1:39848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2720,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" mod_revision:26311 > success:<request_put:<key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" value_size:2647 >> failure:<request_range:<key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" > >"}
{"level":"info","ts":"2026-09-19T11:26:36.170838Z","caller":"traceutil/trace.go:171","msg":"trace[878686194] linearizableReadLoop","detail":"{readStateIndex:27188; appliedIndex:27187; }","duration":"249.768051ms","start":"2026-09-19T11:26:35.921035Z","end":"2026-09-19T11:26:36.170803Z","steps":["trace[878686194] 'read index received'  (duration: 247.804117ms)","trace[878686194] 'applied index is now lower than readState.Index'  (duration: 1.957634ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:36.170898Z","caller":"traceutil/trace.go:171","msg":"trace[1498162913] transaction","detail":"{read_only:false; response_revision:26314; number_of_response:1; }","duration":"347.534581ms","start":"2026-09-19T11:26:35.82331Z","end":"2026-09-19T11:26:36.170844Z","steps":["trace[1498162913] 'process raft request'  (duration: 345.574888ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:36.171085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.055113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:26:36.171129Z","caller":"traceutil/trace.go:171","msg":"trace[911428218] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:0; response_revision:26314; }","duration":"244.122655ms","start":"2026-09-19T11:26:35.926995Z","end":"2026-09-19T11:26:36.171117Z","steps":["trace[911428218] 'agreement among raft nodes before linearized reading'  (duration: 244.012852ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:36.17129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:35.823281Z","time spent":"347.805837ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:26296 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-19T11:26:36.171094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.476101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:26:36.172117Z","caller":"traceutil/trace.go:171","msg":"trace[536600825] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:26314; }","duration":"375.580216ms","start":"2026-09-19T11:26:35.79651Z","end":"2026-09-19T11:26:36.17209Z","steps":["trace[536600825] 'agreement among raft nodes before linearized reading'  (duration: 374.397259ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:36.17218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:35.796483Z","time spent":"375.676979ms","remote":"127.0.0.1:39378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "}
{"level":"info","ts":"2026-09-19T11:26:36.330178Z","caller":"traceutil/trace.go:171","msg":"trace[193758361] transaction","detail":"{read_only:false; response_revision:26316; number_of_response:1; }","duration":"141.570437ms","start":"2026-09-19T11:26:36.188571Z","end":"2026-09-19T11:26:36.330141Z","steps":["trace[193758361] 'process raft request'  (duration: 141.324412ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:36.731894Z","caller":"traceutil/trace.go:171","msg":"trace[1270254487] transaction","detail":"{read_only:false; response_revision:26317; number_of_response:1; }","duration":"543.176724ms","start":"2026-09-19T11:26:36.18868Z","end":"2026-09-19T11:26:36.731857Z","steps":["trace[1270254487] 'process raft request'  (duration: 542.714304ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:36.731907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:36.188549Z","time spent":"543.348868ms","remote":"127.0.0.1:50208","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"info","ts":"2026-09-19T11:26:36.731976Z","caller":"traceutil/trace.go:171","msg":"trace[186482552] transaction","detail":"{read_only:false; response_revision:26322; number_of_response:1; }","duration":"119.166027ms","start":"2026-09-19T11:26:36.612744Z","end":"2026-09-19T11:26:36.73191Z","steps":["trace[186482552] 'process raft request'  (duration: 119.125436ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:36.73206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:36.18866Z","time spent":"543.322888ms","remote":"127.0.0.1:49068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1237,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/endpointslices/openstack/rabbitmq-manila-nodes-rdzbn\" mod_revision:0 > success:<request_put:<key:\"/registry/endpointslices/openstack/rabbitmq-manila-nodes-rdzbn\" value_size:1167 >> failure:<>"}
{"level":"info","ts":"2026-09-19T11:26:36.732197Z","caller":"traceutil/trace.go:171","msg":"trace[779848598] transaction","detail":"{read_only:false; response_revision:26319; number_of_response:1; }","duration":"471.675061ms","start":"2026-09-19T11:26:36.260488Z","end":"2026-09-19T11:26:36.732163Z","steps":["trace[779848598] 'process raft request'  (duration: 471.25108ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:36.732259Z","caller":"traceutil/trace.go:171","msg":"trace[1526201729] transaction","detail":"{read_only:false; response_revision:26320; number_of_response:1; }","duration":"408.567953ms","start":"2026-09-19T11:26:36.323679Z","end":"2026-09-19T11:26:36.732247Z","steps":["trace[1526201729] 'process raft request'  (duration: 408.112332ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:36.732237Z","caller":"traceutil/trace.go:171","msg":"trace[526832804] transaction","detail":"{read_only:false; response_revision:26318; number_of_response:1; }","duration":"540.495085ms","start":"2026-09-19T11:26:36.191684Z","end":"2026-09-19T11:26:36.732179Z","steps":["trace[526832804] 'process raft request'  (duration: 539.888831ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:36.732311Z","caller":"traceutil/trace.go:171","msg":"trace[301895775] transaction","detail":"{read_only:false; response_revision:26321; number_of_response:1; }","duration":"406.61199ms","start":"2026-09-19T11:26:36.325663Z","end":"2026-09-19T11:26:36.732275Z","steps":["trace[301895775] 'process raft request'  (duration: 406.162279ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:36.732406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:36.323651Z","time spent":"408.642055ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:26301 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-09-19T11:26:36.732331Z","caller":"traceutil/trace.go:171","msg":"trace[1633719538] linearizableReadLoop","detail":"{readStateIndex:27192; appliedIndex:27189; }","duration":"541.735933ms","start":"2026-09-19T11:26:36.190549Z","end":"2026-09-19T11:26:36.732285Z","steps":["trace[1633719538] 'read index received'  (duration: 139.352689ms)","trace[1633719538] 'applied index is now lower than readState.Index'  (duration: 402.378044ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:26:36.73242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:36.260457Z","time spent":"471.840734ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:26241 > success:<request_put:<key:\"/registry/leases/kube-node-lease/instance\" value_size:471 >> failure:<request_range:<key:\"/registry/leases/kube-node-lease/instance\" > >"}
{"level":"warn","ts":"2026-09-19T11:26:36.732484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:36.191666Z","time spent":"540.694989ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":129439,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/ranges/serviceips\" mod_revision:26008 > success:<request_put:<key:\"/registry/ranges/serviceips\" value_size:129402 >> failure:<request_range:<key:\"/registry/ranges/serviceips\" > >"}
{"level":"warn","ts":"2026-09-19T11:26:36.732529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:36.325652Z","time spent":"406.781394ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:26300 > 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-09-19T11:26:36.732571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.012079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"}
{"level":"info","ts":"2026-09-19T11:26:36.73263Z","caller":"traceutil/trace.go:171","msg":"trace[1261362528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26322; }","duration":"542.127982ms","start":"2026-09-19T11:26:36.190491Z","end":"2026-09-19T11:26:36.732619Z","steps":["trace[1261362528] 'agreement among raft nodes before linearized reading'  (duration: 541.926757ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:36.732663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:36.190466Z","time spent":"542.187113ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-09-19T11:26:36.732746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.204516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"}
{"level":"warn","ts":"2026-09-19T11:26:36.732754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.033373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-09-19T11:26:36.732817Z","caller":"traceutil/trace.go:171","msg":"trace[1713549237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26322; }","duration":"518.120286ms","start":"2026-09-19T11:26:36.214682Z","end":"2026-09-19T11:26:36.732803Z","steps":["trace[1713549237] 'agreement among raft nodes before linearized reading'  (duration: 517.920951ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:36.73286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:36.214634Z","time spent":"518.217978ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"info","ts":"2026-09-19T11:26:36.732854Z","caller":"traceutil/trace.go:171","msg":"trace[1064077581] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26322; }","duration":"539.270228ms","start":"2026-09-19T11:26:36.19351Z","end":"2026-09-19T11:26:36.73278Z","steps":["trace[1064077581] 'agreement among raft nodes before linearized reading'  (duration: 539.059324ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:36.732808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.16603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-09-19T11:26:36.732917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:36.193499Z","time spent":"539.408271ms","remote":"127.0.0.1:48994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "}
{"level":"info","ts":"2026-09-19T11:26:36.732964Z","caller":"traceutil/trace.go:171","msg":"trace[1489644346] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:26322; }","duration":"120.367335ms","start":"2026-09-19T11:26:36.61257Z","end":"2026-09-19T11:26:36.732938Z","steps":["trace[1489644346] 'agreement among raft nodes before linearized reading'  (duration: 120.102889ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:36.732866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.201223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"}
{"level":"info","ts":"2026-09-19T11:26:36.733055Z","caller":"traceutil/trace.go:171","msg":"trace[1814653625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26322; }","duration":"121.447158ms","start":"2026-09-19T11:26:36.611591Z","end":"2026-09-19T11:26:36.733038Z","steps":["trace[1814653625] 'agreement among raft nodes before linearized reading'  (duration: 121.08288ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:36.733083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.900124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"}
{"level":"info","ts":"2026-09-19T11:26:36.73316Z","caller":"traceutil/trace.go:171","msg":"trace[984356077] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:26322; }","duration":"197.996516ms","start":"2026-09-19T11:26:36.535139Z","end":"2026-09-19T11:26:36.733136Z","steps":["trace[984356077] 'agreement among raft nodes before linearized reading'  (duration: 197.453014ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:36.734075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.343524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"info","ts":"2026-09-19T11:26:36.734148Z","caller":"traceutil/trace.go:171","msg":"trace[1384784572] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26322; }","duration":"520.423506ms","start":"2026-09-19T11:26:36.213707Z","end":"2026-09-19T11:26:36.734131Z","steps":["trace[1384784572] 'agreement among raft nodes before linearized reading'  (duration: 518.905493ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:36.7342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:36.213659Z","time spent":"520.528349ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"info","ts":"2026-09-19T11:26:36.955503Z","caller":"traceutil/trace.go:171","msg":"trace[1455627202] transaction","detail":"{read_only:false; response_revision:26324; number_of_response:1; }","duration":"221.06705ms","start":"2026-09-19T11:26:36.734402Z","end":"2026-09-19T11:26:36.955469Z","steps":["trace[1455627202] 'process raft request'  (duration: 219.870794ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:36.955695Z","caller":"traceutil/trace.go:171","msg":"trace[403155367] linearizableReadLoop","detail":"{readStateIndex:27199; appliedIndex:27197; }","duration":"218.443932ms","start":"2026-09-19T11:26:36.737219Z","end":"2026-09-19T11:26:36.955663Z","steps":["trace[403155367] 'read index received'  (duration: 7.61558ms)","trace[403155367] 'applied index is now lower than readState.Index'  (duration: 210.827012ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:36.955857Z","caller":"traceutil/trace.go:171","msg":"trace[842931339] transaction","detail":"{read_only:false; response_revision:26325; number_of_response:1; }","duration":"216.363805ms","start":"2026-09-19T11:26:36.739456Z","end":"2026-09-19T11:26:36.95582Z","steps":["trace[842931339] 'process raft request'  (duration: 215.852604ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:36.955872Z","caller":"traceutil/trace.go:171","msg":"trace[1775476210] transaction","detail":"{read_only:false; response_revision:26328; number_of_response:1; }","duration":"215.430174ms","start":"2026-09-19T11:26:36.740366Z","end":"2026-09-19T11:26:36.955796Z","steps":["trace[1775476210] 'process raft request'  (duration: 215.242249ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:36.955934Z","caller":"traceutil/trace.go:171","msg":"trace[1227647124] transaction","detail":"{read_only:false; response_revision:26327; number_of_response:1; }","duration":"215.813023ms","start":"2026-09-19T11:26:36.740091Z","end":"2026-09-19T11:26:36.955904Z","steps":["trace[1227647124] 'process raft request'  (duration: 215.477095ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:36.956039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.78765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-de88c6a0-b117-4844-9935-9b46e6c9080b\" ","response":"range_response_count:1 size:1319"}
{"level":"info","ts":"2026-09-19T11:26:36.956091Z","caller":"traceutil/trace.go:171","msg":"trace[1311269791] range","detail":"{range_begin:/registry/persistentvolumes/pvc-de88c6a0-b117-4844-9935-9b46e6c9080b; range_end:; response_count:1; response_revision:26328; }","duration":"218.909164ms","start":"2026-09-19T11:26:36.737166Z","end":"2026-09-19T11:26:36.956076Z","steps":["trace[1311269791] 'agreement among raft nodes before linearized reading'  (duration: 218.618166ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:36.95619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.70339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:26:36.956231Z","caller":"traceutil/trace.go:171","msg":"trace[724399232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26328; }","duration":"171.787822ms","start":"2026-09-19T11:26:36.784433Z","end":"2026-09-19T11:26:36.956221Z","steps":["trace[724399232] 'agreement among raft nodes before linearized reading'  (duration: 171.70178ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:26:36.956399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.931671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"}
{"level":"info","ts":"2026-09-19T11:26:36.956486Z","caller":"traceutil/trace.go:171","msg":"trace[260108148] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26328; }","duration":"218.056223ms","start":"2026-09-19T11:26:36.738408Z","end":"2026-09-19T11:26:36.956465Z","steps":["trace[260108148] 'agreement among raft nodes before linearized reading'  (duration: 217.724226ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:36.956056Z","caller":"traceutil/trace.go:171","msg":"trace[1840466996] transaction","detail":"{read_only:false; response_revision:26326; number_of_response:1; }","duration":"216.444257ms","start":"2026-09-19T11:26:36.739565Z","end":"2026-09-19T11:26:36.956009Z","steps":["trace[1840466996] 'process raft request'  (duration: 215.876145ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:26:40.416176Z","caller":"traceutil/trace.go:171","msg":"trace[1891515598] linearizableReadLoop","detail":"{readStateIndex:27274; appliedIndex:27273; }","duration":"106.019895ms","start":"2026-09-19T11:26:40.310125Z","end":"2026-09-19T11:26:40.416145Z","steps":["trace[1891515598] 'read index received'  (duration: 34.856257ms)","trace[1891515598] 'applied index is now lower than readState.Index'  (duration: 71.161868ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:26:40.41623Z","caller":"traceutil/trace.go:171","msg":"trace[719063957] transaction","detail":"{read_only:false; response_revision:26398; number_of_response:1; }","duration":"222.166666ms","start":"2026-09-19T11:26:40.194017Z","end":"2026-09-19T11:26:40.416184Z","steps":["trace[719063957] 'process raft request'  (duration: 151.041339ms)","trace[719063957] 'compare'  (duration: 70.921602ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:26:40.416413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.26272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:26:40.417124Z","caller":"traceutil/trace.go:171","msg":"trace[250908929] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26398; }","duration":"107.034867ms","start":"2026-09-19T11:26:40.310051Z","end":"2026-09-19T11:26:40.417086Z","steps":["trace[250908929] 'agreement among raft nodes before linearized reading'  (duration: 106.2586ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:05.952007Z","caller":"traceutil/trace.go:171","msg":"trace[476888151] transaction","detail":"{read_only:false; response_revision:26633; number_of_response:1; }","duration":"128.095468ms","start":"2026-09-19T11:27:05.823873Z","end":"2026-09-19T11:27:05.951969Z","steps":["trace[476888151] 'process raft request'  (duration: 127.861042ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:06.178527Z","caller":"traceutil/trace.go:171","msg":"trace[344151218] transaction","detail":"{read_only:false; response_revision:26634; number_of_response:1; }","duration":"220.408616ms","start":"2026-09-19T11:27:05.958057Z","end":"2026-09-19T11:27:06.178466Z","steps":["trace[344151218] 'process raft request'  (duration: 175.936894ms)","trace[344151218] 'compare'  (duration: 44.001692ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:27:06.178709Z","caller":"traceutil/trace.go:171","msg":"trace[1448224296] transaction","detail":"{read_only:false; response_revision:26635; number_of_response:1; }","duration":"218.876162ms","start":"2026-09-19T11:27:05.959795Z","end":"2026-09-19T11:27:06.178671Z","steps":["trace[1448224296] 'process raft request'  (duration: 218.591856ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:07.057664Z","caller":"traceutil/trace.go:171","msg":"trace[1240166228] transaction","detail":"{read_only:false; response_revision:26637; number_of_response:1; }","duration":"190.619421ms","start":"2026-09-19T11:27:06.867007Z","end":"2026-09-19T11:27:07.057626Z","steps":["trace[1240166228] 'process raft request'  (duration: 190.337645ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:07.094153Z","caller":"traceutil/trace.go:171","msg":"trace[1316023776] transaction","detail":"{read_only:false; response_revision:26639; number_of_response:1; }","duration":"203.959939ms","start":"2026-09-19T11:27:06.890165Z","end":"2026-09-19T11:27:07.094125Z","steps":["trace[1316023776] 'process raft request'  (duration: 203.923499ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:07.094211Z","caller":"traceutil/trace.go:171","msg":"trace[2127379047] transaction","detail":"{read_only:false; response_revision:26638; number_of_response:1; }","duration":"214.076885ms","start":"2026-09-19T11:27:06.880088Z","end":"2026-09-19T11:27:07.094165Z","steps":["trace[2127379047] 'process raft request'  (duration: 213.85745ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:07.397616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.210871ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5320898202178514195 > lease_revoke:<id:49d7a0b93add6bd6>","response":"size:30"}
{"level":"info","ts":"2026-09-19T11:27:07.397857Z","caller":"traceutil/trace.go:171","msg":"trace[56397375] linearizableReadLoop","detail":"{readStateIndex:27521; appliedIndex:27520; }","duration":"268.668092ms","start":"2026-09-19T11:27:07.12917Z","end":"2026-09-19T11:27:07.397838Z","steps":["trace[56397375] 'read index received'  (duration: 113.094543ms)","trace[56397375] 'applied index is now lower than readState.Index'  (duration: 155.572079ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:27:07.398043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.872977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-09-19T11:27:07.398062Z","caller":"traceutil/trace.go:171","msg":"trace[987572298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26639; }","duration":"268.957189ms","start":"2026-09-19T11:27:07.129095Z","end":"2026-09-19T11:27:07.398053Z","steps":["trace[987572298] 'agreement among raft nodes before linearized reading'  (duration: 268.825336ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:07.481636Z","caller":"traceutil/trace.go:171","msg":"trace[2083769895] transaction","detail":"{read_only:false; response_revision:26642; number_of_response:1; }","duration":"163.819433ms","start":"2026-09-19T11:27:07.317752Z","end":"2026-09-19T11:27:07.481571Z","steps":["trace[2083769895] 'process raft request'  (duration: 163.758372ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:07.482032Z","caller":"traceutil/trace.go:171","msg":"trace[1536296628] transaction","detail":"{read_only:false; response_revision:26641; number_of_response:1; }","duration":"166.643606ms","start":"2026-09-19T11:27:07.315324Z","end":"2026-09-19T11:27:07.481967Z","steps":["trace[1536296628] 'process raft request'  (duration: 165.955761ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:07.482313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.976195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3235"}
{"level":"info","ts":"2026-09-19T11:27:07.482386Z","caller":"traceutil/trace.go:171","msg":"trace[1552337386] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26642; }","duration":"315.075066ms","start":"2026-09-19T11:27:07.167271Z","end":"2026-09-19T11:27:07.482346Z","steps":["trace[1552337386] 'agreement among raft nodes before linearized reading'  (duration: 314.859872ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:07.482423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:27:07.167258Z","time spent":"315.154239ms","remote":"127.0.0.1:39848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3259,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "}
{"level":"warn","ts":"2026-09-19T11:27:07.482402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.627966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"}
{"level":"warn","ts":"2026-09-19T11:27:07.482489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.183276ms","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-09-19T11:27:07.482497Z","caller":"traceutil/trace.go:171","msg":"trace[1294827597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26642; }","duration":"344.765469ms","start":"2026-09-19T11:27:07.137706Z","end":"2026-09-19T11:27:07.482472Z","steps":["trace[1294827597] 'agreement among raft nodes before linearized reading'  (duration: 344.580275ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:07.48254Z","caller":"traceutil/trace.go:171","msg":"trace[286206430] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26642; }","duration":"331.281138ms","start":"2026-09-19T11:27:07.151239Z","end":"2026-09-19T11:27:07.48252Z","steps":["trace[286206430] 'agreement among raft nodes before linearized reading'  (duration: 331.029983ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:07.482545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:27:07.137683Z","time spent":"344.852692ms","remote":"127.0.0.1:49058","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":"warn","ts":"2026-09-19T11:27:07.482574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:27:07.151221Z","time spent":"331.344819ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"warn","ts":"2026-09-19T11:27:07.482693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.534233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"}
{"level":"info","ts":"2026-09-19T11:27:07.482737Z","caller":"traceutil/trace.go:171","msg":"trace[2147040574] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26642; }","duration":"349.159588ms","start":"2026-09-19T11:27:07.133563Z","end":"2026-09-19T11:27:07.482723Z","steps":["trace[2147040574] 'agreement among raft nodes before linearized reading'  (duration: 348.424651ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:07.482763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:27:07.133539Z","time spent":"349.217289ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"info","ts":"2026-09-19T11:27:07.509977Z","caller":"traceutil/trace.go:171","msg":"trace[336809350] transaction","detail":"{read_only:false; response_revision:26643; number_of_response:1; }","duration":"106.610868ms","start":"2026-09-19T11:27:07.403317Z","end":"2026-09-19T11:27:07.509928Z","steps":["trace[336809350] 'process raft request'  (duration: 106.471035ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:07.646467Z","caller":"traceutil/trace.go:171","msg":"trace[359964008] transaction","detail":"{read_only:false; response_revision:26644; number_of_response:1; }","duration":"158.501645ms","start":"2026-09-19T11:27:07.487932Z","end":"2026-09-19T11:27:07.646433Z","steps":["trace[359964008] 'process raft request'  (duration: 67.23205ms)","trace[359964008] 'compare'  (duration: 90.784355ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:27:07.646738Z","caller":"traceutil/trace.go:171","msg":"trace[8268499] transaction","detail":"{read_only:false; response_revision:26645; number_of_response:1; }","duration":"158.549857ms","start":"2026-09-19T11:27:07.488178Z","end":"2026-09-19T11:27:07.646728Z","steps":["trace[8268499] 'process raft request'  (duration: 157.951804ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:07.646771Z","caller":"traceutil/trace.go:171","msg":"trace[171444001] linearizableReadLoop","detail":"{readStateIndex:27528; appliedIndex:27524; }","duration":"157.720397ms","start":"2026-09-19T11:27:07.489018Z","end":"2026-09-19T11:27:07.646739Z","steps":["trace[171444001] 'read index received'  (duration: 20.830794ms)","trace[171444001] 'applied index is now lower than readState.Index'  (duration: 136.886643ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:27:07.646836Z","caller":"traceutil/trace.go:171","msg":"trace[1694340531] transaction","detail":"{read_only:false; response_revision:26646; number_of_response:1; }","duration":"158.430543ms","start":"2026-09-19T11:27:07.488393Z","end":"2026-09-19T11:27:07.646824Z","steps":["trace[1694340531] 'process raft request'  (duration: 157.782269ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:07.64711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.070185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"}
{"level":"warn","ts":"2026-09-19T11:27:07.647103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.628411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0\" ","response":"range_response_count:1 size:1566"}
{"level":"info","ts":"2026-09-19T11:27:07.647152Z","caller":"traceutil/trace.go:171","msg":"trace[1434684390] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:26646; }","duration":"158.164447ms","start":"2026-09-19T11:27:07.488974Z","end":"2026-09-19T11:27:07.647139Z","steps":["trace[1434684390] 'agreement among raft nodes before linearized reading'  (duration: 157.941602ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:07.647187Z","caller":"traceutil/trace.go:171","msg":"trace[788026069] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0; range_end:; response_count:1; response_revision:26646; }","duration":"142.752064ms","start":"2026-09-19T11:27:07.504415Z","end":"2026-09-19T11:27:07.647167Z","steps":["trace[788026069] 'agreement among raft nodes before linearized reading'  (duration: 142.550969ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:08.403556Z","caller":"traceutil/trace.go:171","msg":"trace[25839217] transaction","detail":"{read_only:false; response_revision:26648; number_of_response:1; }","duration":"214.148046ms","start":"2026-09-19T11:27:08.189376Z","end":"2026-09-19T11:27:08.403524Z","steps":["trace[25839217] 'process raft request'  (duration: 213.921281ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:08.44162Z","caller":"traceutil/trace.go:171","msg":"trace[1542383867] linearizableReadLoop","detail":"{readStateIndex:27531; appliedIndex:27529; }","duration":"180.862864ms","start":"2026-09-19T11:27:08.26071Z","end":"2026-09-19T11:27:08.441573Z","steps":["trace[1542383867] 'read index received'  (duration: 142.662931ms)","trace[1542383867] 'applied index is now lower than readState.Index'  (duration: 38.198553ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:27:08.44172Z","caller":"traceutil/trace.go:171","msg":"trace[682186998] transaction","detail":"{read_only:false; response_revision:26649; number_of_response:1; }","duration":"251.227293ms","start":"2026-09-19T11:27:08.190456Z","end":"2026-09-19T11:27:08.441683Z","steps":["trace[682186998] 'process raft request'  (duration: 249.664218ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:08.441816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.073089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:27:08.441894Z","caller":"traceutil/trace.go:171","msg":"trace[1869410812] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:26649; }","duration":"181.245062ms","start":"2026-09-19T11:27:08.260629Z","end":"2026-09-19T11:27:08.441874Z","steps":["trace[1869410812] 'agreement among raft nodes before linearized reading'  (duration: 181.070368ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:09.24126Z","caller":"traceutil/trace.go:171","msg":"trace[1708434618] transaction","detail":"{read_only:false; response_revision:26653; number_of_response:1; }","duration":"140.964124ms","start":"2026-09-19T11:27:09.100264Z","end":"2026-09-19T11:27:09.241228Z","steps":["trace[1708434618] 'process raft request'  (duration: 73.806516ms)","trace[1708434618] 'compare'  (duration: 66.585126ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:27:09.241465Z","caller":"traceutil/trace.go:171","msg":"trace[1815583484] transaction","detail":"{read_only:false; response_revision:26654; number_of_response:1; }","duration":"139.43736ms","start":"2026-09-19T11:27:09.101991Z","end":"2026-09-19T11:27:09.241428Z","steps":["trace[1815583484] 'process raft request'  (duration: 139.096252ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:09.582289Z","caller":"traceutil/trace.go:171","msg":"trace[819282302] transaction","detail":"{read_only:false; response_revision:26656; number_of_response:1; }","duration":"176.568179ms","start":"2026-09-19T11:27:09.405681Z","end":"2026-09-19T11:27:09.582249Z","steps":["trace[819282302] 'process raft request'  (duration: 176.333073ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:09.692568Z","caller":"traceutil/trace.go:171","msg":"trace[1779425512] linearizableReadLoop","detail":"{readStateIndex:27539; appliedIndex:27537; }","duration":"181.739864ms","start":"2026-09-19T11:27:09.510796Z","end":"2026-09-19T11:27:09.692536Z","steps":["trace[1779425512] 'read index received'  (duration: 71.171318ms)","trace[1779425512] 'applied index is now lower than readState.Index'  (duration: 110.567066ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:27:09.692664Z","caller":"traceutil/trace.go:171","msg":"trace[610255913] transaction","detail":"{read_only:false; response_revision:26657; number_of_response:1; }","duration":"203.788074ms","start":"2026-09-19T11:27:09.488816Z","end":"2026-09-19T11:27:09.692604Z","steps":["trace[610255913] 'process raft request'  (duration: 152.90742ms)","trace[610255913] 'compare'  (duration: 50.68635ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:27:09.692843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.023309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"}
{"level":"info","ts":"2026-09-19T11:27:09.692875Z","caller":"traceutil/trace.go:171","msg":"trace[1631531547] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:26657; }","duration":"182.132022ms","start":"2026-09-19T11:27:09.51073Z","end":"2026-09-19T11:27:09.692862Z","steps":["trace[1631531547] 'agreement among raft nodes before linearized reading'  (duration: 181.957168ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:09.692959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.139183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-09-19T11:27:09.693052Z","caller":"traceutil/trace.go:171","msg":"trace[1738897565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26657; }","duration":"178.239365ms","start":"2026-09-19T11:27:09.514775Z","end":"2026-09-19T11:27:09.693014Z","steps":["trace[1738897565] 'agreement among raft nodes before linearized reading'  (duration: 177.965458ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:16.673206Z","caller":"traceutil/trace.go:171","msg":"trace[971216392] linearizableReadLoop","detail":"{readStateIndex:27592; appliedIndex:27591; }","duration":"106.26349ms","start":"2026-09-19T11:27:16.566911Z","end":"2026-09-19T11:27:16.673175Z","steps":["trace[971216392] 'read index received'  (duration: 71.984706ms)","trace[971216392] 'applied index is now lower than readState.Index'  (duration: 34.277124ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:27:16.67329Z","caller":"traceutil/trace.go:171","msg":"trace[822706046] transaction","detail":"{read_only:false; response_revision:26709; number_of_response:1; }","duration":"124.034736ms","start":"2026-09-19T11:27:16.549217Z","end":"2026-09-19T11:27:16.673252Z","steps":["trace[822706046] 'process raft request'  (duration: 89.756901ms)","trace[822706046] 'compare'  (duration: 34.05645ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:27:16.673431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.465595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:27:16.673479Z","caller":"traceutil/trace.go:171","msg":"trace[948608984] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:26709; }","duration":"106.627718ms","start":"2026-09-19T11:27:16.566835Z","end":"2026-09-19T11:27:16.673463Z","steps":["trace[948608984] 'agreement among raft nodes before linearized reading'  (duration: 106.486275ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:21.930323Z","caller":"traceutil/trace.go:171","msg":"trace[1871229054] linearizableReadLoop","detail":"{readStateIndex:27630; appliedIndex:27629; }","duration":"101.414522ms","start":"2026-09-19T11:27:21.82888Z","end":"2026-09-19T11:27:21.930294Z","steps":["trace[1871229054] 'read index received'  (duration: 101.150886ms)","trace[1871229054] 'applied index is now lower than readState.Index'  (duration: 261.926µs)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:27:21.930409Z","caller":"traceutil/trace.go:171","msg":"trace[2128517990] transaction","detail":"{read_only:false; response_revision:26746; number_of_response:1; }","duration":"106.467255ms","start":"2026-09-19T11:27:21.823894Z","end":"2026-09-19T11:27:21.930361Z","steps":["trace[2128517990] 'process raft request'  (duration: 106.186549ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:21.930662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.76169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-09-19T11:27:21.930741Z","caller":"traceutil/trace.go:171","msg":"trace[2058855528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26746; }","duration":"101.919833ms","start":"2026-09-19T11:27:21.828801Z","end":"2026-09-19T11:27:21.930721Z","steps":["trace[2058855528] 'agreement among raft nodes before linearized reading'  (duration: 101.674088ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:22.166007Z","caller":"traceutil/trace.go:171","msg":"trace[1418354845] transaction","detail":"{read_only:false; response_revision:26747; number_of_response:1; }","duration":"229.752344ms","start":"2026-09-19T11:27:21.936223Z","end":"2026-09-19T11:27:22.165975Z","steps":["trace[1418354845] 'process raft request'  (duration: 229.55231ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:22.358667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.694948ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5320898202178514459 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:26733 > 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-09-19T11:27:22.358979Z","caller":"traceutil/trace.go:171","msg":"trace[1266238759] transaction","detail":"{read_only:false; response_revision:26750; number_of_response:1; }","duration":"162.814171ms","start":"2026-09-19T11:27:22.196137Z","end":"2026-09-19T11:27:22.358952Z","steps":["trace[1266238759] 'process raft request'  (duration: 162.74729ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:22.359064Z","caller":"traceutil/trace.go:171","msg":"trace[51298831] transaction","detail":"{read_only:false; response_revision:26749; number_of_response:1; }","duration":"421.946631ms","start":"2026-09-19T11:27:21.937088Z","end":"2026-09-19T11:27:22.359035Z","steps":["trace[51298831] 'process raft request'  (duration: 421.703776ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:22.359116Z","caller":"traceutil/trace.go:171","msg":"trace[532823899] linearizableReadLoop","detail":"{readStateIndex:27633; appliedIndex:27630; }","duration":"414.058255ms","start":"2026-09-19T11:27:21.945023Z","end":"2026-09-19T11:27:22.359081Z","steps":["trace[532823899] 'read index received'  (duration: 220.856086ms)","trace[532823899] 'applied index is now lower than readState.Index'  (duration: 193.198559ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:27:22.359132Z","caller":"traceutil/trace.go:171","msg":"trace[1103150293] transaction","detail":"{read_only:false; response_revision:26748; number_of_response:1; }","duration":"422.265558ms","start":"2026-09-19T11:27:21.936814Z","end":"2026-09-19T11:27:22.35908Z","steps":["trace[1103150293] 'process raft request'  (duration: 281.019638ms)","trace[1103150293] 'compare'  (duration: 140.502764ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:27:22.359185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:27:21.937053Z","time spent":"422.069054ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:26735 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:463 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-19T11:27:22.359357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:27:21.936797Z","time spent":"422.440872ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:26733 > 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":"warn","ts":"2026-09-19T11:27:22.359476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.429444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.84\" ","response":"range_response_count:1 size:137"}
{"level":"info","ts":"2026-09-19T11:27:22.359579Z","caller":"traceutil/trace.go:171","msg":"trace[1105256365] range","detail":"{range_begin:/registry/masterleases/162.253.55.84; range_end:; response_count:1; response_revision:26750; }","duration":"414.595457ms","start":"2026-09-19T11:27:21.94496Z","end":"2026-09-19T11:27:22.359555Z","steps":["trace[1105256365] 'agreement among raft nodes before linearized reading'  (duration: 414.25704ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:22.359642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:27:21.944934Z","time spent":"414.692979ms","remote":"127.0.0.1:50174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":161,"request content":"key:\"/registry/masterleases/162.253.55.84\" "}
{"level":"warn","ts":"2026-09-19T11:27:22.649247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.062292ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5320898202178514465 username:\"kube-apiserver-etcd-client\" auth_revision:1 > lease_grant:<ttl:15-second id:49d7a0b93add6e20>","response":"size:42"}
{"level":"info","ts":"2026-09-19T11:27:22.649356Z","caller":"traceutil/trace.go:171","msg":"trace[1780344668] linearizableReadLoop","detail":"{readStateIndex:27635; appliedIndex:27634; }","duration":"214.806131ms","start":"2026-09-19T11:27:22.434531Z","end":"2026-09-19T11:27:22.649337Z","steps":["trace[1780344668] 'read index received'  (duration: 53.564754ms)","trace[1780344668] 'applied index is now lower than readState.Index'  (duration: 161.239517ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:27:22.649686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.125578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:27:22.64982Z","caller":"traceutil/trace.go:171","msg":"trace[1191892927] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:26750; }","duration":"215.319972ms","start":"2026-09-19T11:27:22.434458Z","end":"2026-09-19T11:27:22.649778Z","steps":["trace[1191892927] 'agreement among raft nodes before linearized reading'  (duration: 215.075497ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:22.833448Z","caller":"traceutil/trace.go:171","msg":"trace[280435960] linearizableReadLoop","detail":"{readStateIndex:27637; appliedIndex:27636; }","duration":"165.676634ms","start":"2026-09-19T11:27:22.667728Z","end":"2026-09-19T11:27:22.833405Z","steps":["trace[280435960] 'read index received'  (duration: 129.578139ms)","trace[280435960] 'applied index is now lower than readState.Index'  (duration: 36.097205ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:27:22.833473Z","caller":"traceutil/trace.go:171","msg":"trace[777588859] transaction","detail":"{read_only:false; response_revision:26752; number_of_response:1; }","duration":"175.806851ms","start":"2026-09-19T11:27:22.657628Z","end":"2026-09-19T11:27:22.833435Z","steps":["trace[777588859] 'process raft request'  (duration: 139.46021ms)","trace[777588859] 'compare'  (duration: 36.114846ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:27:22.833747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.994822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"}
{"level":"info","ts":"2026-09-19T11:27:22.833836Z","caller":"traceutil/trace.go:171","msg":"trace[1523782560] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:26752; }","duration":"166.289018ms","start":"2026-09-19T11:27:22.667527Z","end":"2026-09-19T11:27:22.833816Z","steps":["trace[1523782560] 'agreement among raft nodes before linearized reading'  (duration: 165.981382ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:22.932021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.705824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-09-19T11:27:22.93205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.042812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"}
{"level":"info","ts":"2026-09-19T11:27:22.932134Z","caller":"traceutil/trace.go:171","msg":"trace[1270304760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26753; }","duration":"147.924829ms","start":"2026-09-19T11:27:22.784186Z","end":"2026-09-19T11:27:22.932111Z","steps":["trace[1270304760] 'agreement among raft nodes before linearized reading'  (duration: 147.640332ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:22.932168Z","caller":"traceutil/trace.go:171","msg":"trace[1837020039] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26753; }","duration":"222.224566ms","start":"2026-09-19T11:27:22.70991Z","end":"2026-09-19T11:27:22.932134Z","steps":["trace[1837020039] 'agreement among raft nodes before linearized reading'  (duration: 221.93544ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:22.932276Z","caller":"traceutil/trace.go:171","msg":"trace[1941799062] transaction","detail":"{read_only:false; response_revision:26753; number_of_response:1; }","duration":"226.45516ms","start":"2026-09-19T11:27:22.705781Z","end":"2026-09-19T11:27:22.932236Z","steps":["trace[1941799062] 'process raft request'  (duration: 225.680362ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:27.059246Z","caller":"traceutil/trace.go:171","msg":"trace[1074406597] transaction","detail":"{read_only:false; response_revision:26782; number_of_response:1; }","duration":"111.352913ms","start":"2026-09-19T11:27:26.947836Z","end":"2026-09-19T11:27:27.059189Z","steps":["trace[1074406597] 'process raft request'  (duration: 64.574419ms)","trace[1074406597] 'compare'  (duration: 46.118629ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:27:27.05967Z","caller":"traceutil/trace.go:171","msg":"trace[850005848] linearizableReadLoop","detail":"{readStateIndex:27667; appliedIndex:27666; }","duration":"105.744019ms","start":"2026-09-19T11:27:26.95328Z","end":"2026-09-19T11:27:27.059024Z","steps":["trace[850005848] 'read index received'  (duration: 59.066617ms)","trace[850005848] 'applied index is now lower than readState.Index'  (duration: 46.675942ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:27:27.059898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.585128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"}
{"level":"info","ts":"2026-09-19T11:27:27.06Z","caller":"traceutil/trace.go:171","msg":"trace[1527263834] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26782; }","duration":"106.780742ms","start":"2026-09-19T11:27:26.953192Z","end":"2026-09-19T11:27:27.059973Z","steps":["trace[1527263834] 'agreement among raft nodes before linearized reading'  (duration: 106.379163ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:32.562245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.376045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-09-19T11:27:32.562334Z","caller":"traceutil/trace.go:171","msg":"trace[2064429675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26823; }","duration":"116.515378ms","start":"2026-09-19T11:27:32.4458Z","end":"2026-09-19T11:27:32.562315Z","steps":["trace[2064429675] 'range keys from in-memory index tree'  (duration: 116.204401ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:32.562489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.222166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"info","ts":"2026-09-19T11:27:32.562575Z","caller":"traceutil/trace.go:171","msg":"trace[1437489107] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26823; }","duration":"109.3919ms","start":"2026-09-19T11:27:32.453161Z","end":"2026-09-19T11:27:32.562553Z","steps":["trace[1437489107] 'range keys from in-memory index tree'  (duration: 108.979291ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:33.423223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.353609ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5320898202178514656 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:26815 > success:<request_put:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-19T11:27:33.423366Z","caller":"traceutil/trace.go:171","msg":"trace[1349454583] transaction","detail":"{read_only:false; response_revision:26829; number_of_response:1; }","duration":"164.405167ms","start":"2026-09-19T11:27:33.258937Z","end":"2026-09-19T11:27:33.423342Z","steps":["trace[1349454583] 'process raft request'  (duration: 41.807293ms)","trace[1349454583] 'compare'  (duration: 122.202454ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:27:33.810456Z","caller":"traceutil/trace.go:171","msg":"trace[1801592036] transaction","detail":"{read_only:false; response_revision:26832; number_of_response:1; }","duration":"130.636704ms","start":"2026-09-19T11:27:33.679791Z","end":"2026-09-19T11:27:33.810428Z","steps":["trace[1801592036] 'process raft request'  (duration: 130.42544ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:33.914927Z","caller":"traceutil/trace.go:171","msg":"trace[37434851] transaction","detail":"{read_only:false; response_revision:26833; number_of_response:1; }","duration":"101.100065ms","start":"2026-09-19T11:27:33.813793Z","end":"2026-09-19T11:27:33.914894Z","steps":["trace[37434851] 'process raft request'  (duration: 66.549244ms)","trace[37434851] 'compare'  (duration: 34.231434ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:27:34.13596Z","caller":"traceutil/trace.go:171","msg":"trace[1219332188] transaction","detail":"{read_only:false; response_revision:26834; number_of_response:1; }","duration":"131.964744ms","start":"2026-09-19T11:27:34.003962Z","end":"2026-09-19T11:27:34.135926Z","steps":["trace[1219332188] 'process raft request'  (duration: 64.009558ms)","trace[1219332188] 'compare'  (duration: 67.801412ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:27:57.662538Z","caller":"traceutil/trace.go:171","msg":"trace[1637058782] transaction","detail":"{read_only:false; response_revision:27158; number_of_response:1; }","duration":"144.996075ms","start":"2026-09-19T11:27:57.517504Z","end":"2026-09-19T11:27:57.6625Z","steps":["trace[1637058782] 'process raft request'  (duration: 144.755839ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:57.786147Z","caller":"traceutil/trace.go:171","msg":"trace[236074916] transaction","detail":"{read_only:false; response_revision:27159; number_of_response:1; }","duration":"266.877952ms","start":"2026-09-19T11:27:57.519213Z","end":"2026-09-19T11:27:57.786091Z","steps":["trace[236074916] 'process raft request'  (duration: 180.552997ms)","trace[236074916] 'compare'  (duration: 85.804584ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:27:57.786298Z","caller":"traceutil/trace.go:171","msg":"trace[1896074460] transaction","detail":"{read_only:false; response_revision:27160; number_of_response:1; }","duration":"190.267923ms","start":"2026-09-19T11:27:57.595975Z","end":"2026-09-19T11:27:57.786243Z","steps":["trace[1896074460] 'process raft request'  (duration: 189.976097ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:58.312219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.1337ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5320898202178515436 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:27147 > 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\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-19T11:27:58.312361Z","caller":"traceutil/trace.go:171","msg":"trace[319780235] linearizableReadLoop","detail":"{readStateIndex:28056; appliedIndex:28055; }","duration":"161.982552ms","start":"2026-09-19T11:27:58.150356Z","end":"2026-09-19T11:27:58.312339Z","steps":["trace[319780235] 'read index received'  (duration: 81.301µs)","trace[319780235] 'applied index is now lower than readState.Index'  (duration: 161.898521ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:27:58.312448Z","caller":"traceutil/trace.go:171","msg":"trace[1253060504] transaction","detail":"{read_only:false; response_revision:27162; number_of_response:1; }","duration":"253.78384ms","start":"2026-09-19T11:27:58.058627Z","end":"2026-09-19T11:27:58.312411Z","steps":["trace[1253060504] 'process raft request'  (duration: 72.311472ms)","trace[1253060504] 'compare'  (duration: 180.782443ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:27:58.31264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.28234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:531"}
{"level":"info","ts":"2026-09-19T11:27:58.312678Z","caller":"traceutil/trace.go:171","msg":"trace[1668797666] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:27162; }","duration":"162.362201ms","start":"2026-09-19T11:27:58.150299Z","end":"2026-09-19T11:27:58.312661Z","steps":["trace[1668797666] 'agreement among raft nodes before linearized reading'  (duration: 162.169517ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:58.536876Z","caller":"traceutil/trace.go:171","msg":"trace[553274752] linearizableReadLoop","detail":"{readStateIndex:28058; appliedIndex:28056; }","duration":"129.168871ms","start":"2026-09-19T11:27:58.40767Z","end":"2026-09-19T11:27:58.536838Z","steps":["trace[553274752] 'read index received'  (duration: 72.562799ms)","trace[553274752] 'applied index is now lower than readState.Index'  (duration: 56.604402ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:27:58.537021Z","caller":"traceutil/trace.go:171","msg":"trace[1445431025] transaction","detail":"{read_only:false; response_revision:27163; number_of_response:1; }","duration":"219.796851ms","start":"2026-09-19T11:27:58.317188Z","end":"2026-09-19T11:27:58.536985Z","steps":["trace[1445431025] 'process raft request'  (duration: 163.062906ms)","trace[1445431025] 'compare'  (duration: 56.319176ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:27:58.537059Z","caller":"traceutil/trace.go:171","msg":"trace[1761077636] transaction","detail":"{read_only:false; response_revision:27164; number_of_response:1; }","duration":"219.115487ms","start":"2026-09-19T11:27:58.317888Z","end":"2026-09-19T11:27:58.537004Z","steps":["trace[1761077636] 'process raft request'  (duration: 218.894312ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:58.537105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.416686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:27:58.537325Z","caller":"traceutil/trace.go:171","msg":"trace[715454904] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27164; }","duration":"129.712852ms","start":"2026-09-19T11:27:58.407598Z","end":"2026-09-19T11:27:58.537311Z","steps":["trace[715454904] 'agreement among raft nodes before linearized reading'  (duration: 129.437716ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:58.537451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.466817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:27:58.5375Z","caller":"traceutil/trace.go:171","msg":"trace[754649542] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:27164; }","duration":"129.539769ms","start":"2026-09-19T11:27:58.407947Z","end":"2026-09-19T11:27:58.537486Z","steps":["trace[754649542] 'agreement among raft nodes before linearized reading'  (duration: 129.220012ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:58.537562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.442316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:27:58.537645Z","caller":"traceutil/trace.go:171","msg":"trace[1672295663] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27164; }","duration":"129.604761ms","start":"2026-09-19T11:27:58.408027Z","end":"2026-09-19T11:27:58.537631Z","steps":["trace[1672295663] 'agreement among raft nodes before linearized reading'  (duration: 129.398366ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:58.537545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.041008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-09-19T11:27:58.537632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.327235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"}
{"level":"info","ts":"2026-09-19T11:27:58.537712Z","caller":"traceutil/trace.go:171","msg":"trace[145916596] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27164; }","duration":"129.248314ms","start":"2026-09-19T11:27:58.408437Z","end":"2026-09-19T11:27:58.537685Z","steps":["trace[145916596] 'agreement among raft nodes before linearized reading'  (duration: 128.930106ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:58.537744Z","caller":"traceutil/trace.go:171","msg":"trace[1786567590] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27164; }","duration":"125.503969ms","start":"2026-09-19T11:27:58.412214Z","end":"2026-09-19T11:27:58.537718Z","steps":["trace[1786567590] 'agreement among raft nodes before linearized reading'  (duration: 125.15021ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:59.055271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.174979ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5320898202178515446 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:26949 > success:<request_put:<key:\"/registry/leases/kube-node-lease/instance\" value_size:472 >> failure:<request_range:<key:\"/registry/leases/kube-node-lease/instance\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-19T11:27:59.055425Z","caller":"traceutil/trace.go:171","msg":"trace[1375137207] linearizableReadLoop","detail":"{readStateIndex:28061; appliedIndex:28060; }","duration":"319.788572ms","start":"2026-09-19T11:27:58.73559Z","end":"2026-09-19T11:27:59.055379Z","steps":["trace[1375137207] 'read index received'  (duration: 82.419369ms)","trace[1375137207] 'applied index is now lower than readState.Index'  (duration: 237.367143ms)"],"step_count":2}
{"level":"info","ts":"2026-09-19T11:27:59.055484Z","caller":"traceutil/trace.go:171","msg":"trace[1672865399] transaction","detail":"{read_only:false; response_revision:27167; number_of_response:1; }","duration":"378.657866ms","start":"2026-09-19T11:27:58.676784Z","end":"2026-09-19T11:27:59.055442Z","steps":["trace[1672865399] 'process raft request'  (duration: 141.19487ms)","trace[1672865399] 'compare'  (duration: 236.847512ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-19T11:27:59.05562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.030757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-09-19T11:27:59.055657Z","caller":"traceutil/trace.go:171","msg":"trace[467823690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27167; }","duration":"320.112219ms","start":"2026-09-19T11:27:58.735533Z","end":"2026-09-19T11:27:59.055645Z","steps":["trace[467823690] 'agreement among raft nodes before linearized reading'  (duration: 319.971916ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:59.05568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:27:58.676754Z","time spent":"378.820769ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:26949 > success:<request_put:<key:\"/registry/leases/kube-node-lease/instance\" value_size:472 >> failure:<request_range:<key:\"/registry/leases/kube-node-lease/instance\" > >"}
{"level":"warn","ts":"2026-09-19T11:27:59.055689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:27:58.735515Z","time spent":"320.165551ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-09-19T11:27:59.055834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.142291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-19T11:27:59.055941Z","caller":"traceutil/trace.go:171","msg":"trace[1916058918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27167; }","duration":"273.323256ms","start":"2026-09-19T11:27:58.782595Z","end":"2026-09-19T11:27:59.055918Z","steps":["trace[1916058918] 'agreement among raft nodes before linearized reading'  (duration: 273.131471ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-19T11:27:59.056209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.697529ms","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":"warn","ts":"2026-09-19T11:27:59.056254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.834393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"info","ts":"2026-09-19T11:27:59.056325Z","caller":"traceutil/trace.go:171","msg":"trace[736210140] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:27167; }","duration":"262.857352ms","start":"2026-09-19T11:27:58.79344Z","end":"2026-09-19T11:27:59.056298Z","steps":["trace[736210140] 'agreement among raft nodes before linearized reading'  (duration: 262.593657ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:59.056365Z","caller":"traceutil/trace.go:171","msg":"trace[503112644] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:27167; }","duration":"233.006917ms","start":"2026-09-19T11:27:58.823326Z","end":"2026-09-19T11:27:59.056333Z","steps":["trace[503112644] 'agreement among raft nodes before linearized reading'  (duration: 232.66187ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:27:59.165556Z","caller":"traceutil/trace.go:171","msg":"trace[252820591] transaction","detail":"{read_only:false; response_revision:27169; number_of_response:1; }","duration":"101.79023ms","start":"2026-09-19T11:27:59.063694Z","end":"2026-09-19T11:27:59.165484Z","steps":["trace[252820591] 'process raft request'  (duration: 96.84458ms)"],"step_count":1}
{"level":"info","ts":"2026-09-19T11:28:58.485076Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24869}
{"level":"info","ts":"2026-09-19T11:28:58.659855Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24869,"took":"169.015119ms","hash":2079203212,"current-db-size-bytes":59871232,"current-db-size":"60 MB","current-db-size-in-use-bytes":36286464,"current-db-size-in-use":"36 MB"}
{"level":"info","ts":"2026-09-19T11:28:58.65997Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2079203212,"revision":24869,"compact-revision":21624}
{"level":"info","ts":"2026-09-19T11:31:14.097208Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca4c710a8d5349d7","local-member-applied-index":30004,"local-member-snapshot-index":20003,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-09-19T11:31:14.103891Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004}
{"level":"info","ts":"2026-09-19T11:31:14.104036Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004}
{"level":"info","ts":"2026-09-19T11:33:58.493176Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27687}
{"level":"info","ts":"2026-09-19T11:33:58.644019Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27687,"took":"145.04878ms","hash":3441623997,"current-db-size-bytes":59871232,"current-db-size":"60 MB","current-db-size-in-use-bytes":34295808,"current-db-size-in-use":"34 MB"}
{"level":"info","ts":"2026-09-19T11:33:58.644118Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3441623997,"revision":27687,"compact-revision":24869}
{"level":"info","ts":"2026-09-19T11:38:58.501481Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30335}
{"level":"info","ts":"2026-09-19T11:38:58.662989Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30335,"took":"155.133065ms","hash":86112213,"current-db-size-bytes":59871232,"current-db-size":"60 MB","current-db-size-in-use-bytes":30511104,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-09-19T11:38:58.66311Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":86112213,"revision":30335,"compact-revision":27687}
{"level":"info","ts":"2026-09-19T11:43:58.510764Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32584}
{"level":"info","ts":"2026-09-19T11:43:58.673877Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32584,"took":"157.664709ms","hash":223035312,"current-db-size-bytes":60911616,"current-db-size":"61 MB","current-db-size-in-use-bytes":30060544,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-09-19T11:43:58.673972Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":223035312,"revision":32584,"compact-revision":30335}
{"level":"info","ts":"2026-09-19T11:48:58.519874Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34807}
{"level":"info","ts":"2026-09-19T11:48:58.659758Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34807,"took":"133.108923ms","hash":2266548665,"current-db-size-bytes":60911616,"current-db-size":"61 MB","current-db-size-in-use-bytes":31051776,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-09-19T11:48:58.659845Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2266548665,"revision":34807,"compact-revision":32584}
{"level":"info","ts":"2026-09-19T11:52:37.999804Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca4c710a8d5349d7","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-09-19T11:52:38.010566Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005}
{"level":"info","ts":"2026-09-19T11:52:38.010773Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005}
{"level":"info","ts":"2026-09-19T11:53:58.527477Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37102}
{"level":"info","ts":"2026-09-19T11:53:58.666727Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37102,"took":"133.759174ms","hash":1899141404,"current-db-size-bytes":60911616,"current-db-size":"61 MB","current-db-size-in-use-bytes":32403456,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-09-19T11:53:58.666809Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1899141404,"revision":37102,"compact-revision":34807}
{"level":"info","ts":"2026-09-19T11:58:58.546068Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39374}
{"level":"info","ts":"2026-09-19T11:58:58.685207Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39374,"took":"133.850925ms","hash":4083141745,"current-db-size-bytes":61759488,"current-db-size":"62 MB","current-db-size-in-use-bytes":31342592,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-09-19T11:58:58.685308Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4083141745,"revision":39374,"compact-revision":37102}
{"level":"info","ts":"2026-09-19T12:03:58.55569Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41605}
{"level":"info","ts":"2026-09-19T12:03:58.687914Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41605,"took":"126.47802ms","hash":2424767438,"current-db-size-bytes":61759488,"current-db-size":"62 MB","current-db-size-in-use-bytes":30724096,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-09-19T12:03:58.687996Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2424767438,"revision":41605,"compact-revision":39374}