{"level":"warn","ts":"2026-04-07T00:06:42.957956Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-04-07T00:06:42.958207Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-04-07T00:06:42.958278Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-04-07T00:06:42.95829Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]}
{"level":"info","ts":"2026-04-07T00:06:42.958316Z","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-04-07T00:06:42.95926Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]}
{"level":"info","ts":"2026-04-07T00:06:42.959357Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-04-07T00:06:42.967925Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.315791ms"}
{"level":"info","ts":"2026-04-07T00:06:42.975Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"}
{"level":"info","ts":"2026-04-07T00:06:42.97511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"}
{"level":"info","ts":"2026-04-07T00:06:42.975137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"}
{"level":"info","ts":"2026-04-07T00:06:42.975157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-04-07T00:06:42.975166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"}
{"level":"info","ts":"2026-04-07T00:06:42.975252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"}
{"level":"warn","ts":"2026-04-07T00:06:42.984336Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-04-07T00:06:42.986338Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-04-07T00:06:42.987412Z","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-04-07T00:06:42.989557Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-04-07T00:06:42.989724Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-04-07T00:06:42.990333Z","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-04-07T00:06:42.990434Z","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-04-07T00:06:42.990449Z","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-04-07T00:06:42.993005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"}
{"level":"info","ts":"2026-04-07T00:06:42.993656Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]}
{"level":"info","ts":"2026-04-07T00:06:42.995419Z","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-04-07T00:06:42.995519Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"}
{"level":"info","ts":"2026-04-07T00:06:42.995561Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"}
{"level":"info","ts":"2026-04-07T00:06:42.995729Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-04-07T00:06:42.995758Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-04-07T00:06:43.47632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"}
{"level":"info","ts":"2026-04-07T00:06:43.476405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"}
{"level":"info","ts":"2026-04-07T00:06:43.476469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"}
{"level":"info","ts":"2026-04-07T00:06:43.476492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"}
{"level":"info","ts":"2026-04-07T00:06:43.476505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"}
{"level":"info","ts":"2026-04-07T00:06:43.476527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"}
{"level":"info","ts":"2026-04-07T00:06:43.476547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"}
{"level":"info","ts":"2026-04-07T00:06:43.482013Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-07T00:06:43.483958Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-04-07T00:06:43.483952Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"}
{"level":"info","ts":"2026-04-07T00:06:43.484068Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-04-07T00:06:43.484338Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-04-07T00:06:43.484369Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-07T00:06:43.484425Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-04-07T00:06:43.484469Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-07T00:06:43.484496Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-07T00:06:43.486669Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"}
{"level":"info","ts":"2026-04-07T00:06:43.487346Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-04-07T00:07:08.215401Z","caller":"traceutil/trace.go:171","msg":"trace[1561994314] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"155.339816ms","start":"2026-04-07T00:07:08.060027Z","end":"2026-04-07T00:07:08.215367Z","steps":["trace[1561994314] 'process raft request'  (duration: 155.166532ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:07:12.914427Z","caller":"traceutil/trace.go:171","msg":"trace[1045828309] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"240.913612ms","start":"2026-04-07T00:07:12.673438Z","end":"2026-04-07T00:07:12.914352Z","steps":["trace[1045828309] 'process raft request'  (duration: 215.447332ms)","trace[1045828309] 'compare'  (duration: 24.526921ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:07:12.914761Z","caller":"traceutil/trace.go:171","msg":"trace[1093493673] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"239.720937ms","start":"2026-04-07T00:07:12.674955Z","end":"2026-04-07T00:07:12.914676Z","steps":["trace[1093493673] 'process raft request'  (duration: 239.37048ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:07:14.759556Z","caller":"traceutil/trace.go:171","msg":"trace[616664479] transaction","detail":"{read_only:false; number_of_response:1; response_revision:483; }","duration":"108.252338ms","start":"2026-04-07T00:07:14.651278Z","end":"2026-04-07T00:07:14.759531Z","steps":["trace[616664479] 'process raft request'  (duration: 107.494751ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:07:14.766217Z","caller":"traceutil/trace.go:171","msg":"trace[1508401727] transaction","detail":"{read_only:false; number_of_response:1; response_revision:483; }","duration":"107.723476ms","start":"2026-04-07T00:07:14.65844Z","end":"2026-04-07T00:07:14.766164Z","steps":["trace[1508401727] 'process raft request'  (duration: 107.489571ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:07:14.766595Z","caller":"traceutil/trace.go:171","msg":"trace[352540695] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"107.831718ms","start":"2026-04-07T00:07:14.658739Z","end":"2026-04-07T00:07:14.766571Z","steps":["trace[352540695] 'process raft request'  (duration: 107.294607ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:07:21.292615Z","caller":"traceutil/trace.go:171","msg":"trace[1310806346] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"125.399771ms","start":"2026-04-07T00:07:21.167183Z","end":"2026-04-07T00:07:21.292582Z","steps":["trace[1310806346] 'process raft request'  (duration: 120.011777ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:07:21.992347Z","caller":"traceutil/trace.go:171","msg":"trace[1564147170] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"429.093886ms","start":"2026-04-07T00:07:21.563219Z","end":"2026-04-07T00:07:21.992313Z","steps":["trace[1564147170] 'process raft request'  (duration: 428.512963ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:07:21.993057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:21.563179Z","time spent":"429.259259ms","remote":"127.0.0.1:41112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" mod_revision:577 > success:<request_put:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" value_size:1036225 >> failure:<request_range:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" > >"}
{"level":"info","ts":"2026-04-07T00:08:29.151038Z","caller":"traceutil/trace.go:171","msg":"trace[183860647] linearizableReadLoop","detail":"{readStateIndex:1455; appliedIndex:1454; }","duration":"151.714915ms","start":"2026-04-07T00:08:28.999273Z","end":"2026-04-07T00:08:29.150988Z","steps":["trace[183860647] 'read index received'  (duration: 71.983135ms)","trace[183860647] 'applied index is now lower than readState.Index'  (duration: 79.72945ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:08:29.15128Z","caller":"traceutil/trace.go:171","msg":"trace[579231098] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"188.961274ms","start":"2026-04-07T00:08:28.962273Z","end":"2026-04-07T00:08:29.151234Z","steps":["trace[579231098] 'process raft request'  (duration: 109.105282ms)","trace[579231098] 'compare'  (duration: 79.28899ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:08:29.151352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.037141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-04-07T00:08:29.151459Z","caller":"traceutil/trace.go:171","msg":"trace[990443043] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1412; }","duration":"152.186784ms","start":"2026-04-07T00:08:28.999256Z","end":"2026-04-07T00:08:29.151443Z","steps":["trace[990443043] 'agreement among raft nodes before linearized reading'  (duration: 151.934959ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:08:53.370997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.136407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-04-07T00:08:53.371108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.287143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a3ea84012cd92d\" ","response":"range_response_count:1 size:982"}
{"level":"info","ts":"2026-04-07T00:08:53.371149Z","caller":"traceutil/trace.go:171","msg":"trace[1207171539] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a3ea84012cd92d; range_end:; response_count:1; response_revision:1807; }","duration":"148.366125ms","start":"2026-04-07T00:08:53.22277Z","end":"2026-04-07T00:08:53.371136Z","steps":["trace[1207171539] 'range keys from in-memory index tree'  (duration: 148.1536ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:08:53.371029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.370424ms","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-04-07T00:08:53.371193Z","caller":"traceutil/trace.go:171","msg":"trace[879208884] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1807; }","duration":"137.576959ms","start":"2026-04-07T00:08:53.233603Z","end":"2026-04-07T00:08:53.37118Z","steps":["trace[879208884] 'range keys from in-memory index tree'  (duration: 137.12268ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:08:53.371141Z","caller":"traceutil/trace.go:171","msg":"trace[817360232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1807; }","duration":"209.364312ms","start":"2026-04-07T00:08:53.161753Z","end":"2026-04-07T00:08:53.371117Z","steps":["trace[817360232] 'range keys from in-memory index tree'  (duration: 209.006725ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:08:53.371351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.81906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-04-07T00:08:53.371463Z","caller":"traceutil/trace.go:171","msg":"trace[1936834901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1807; }","duration":"100.982983ms","start":"2026-04-07T00:08:53.270452Z","end":"2026-04-07T00:08:53.371435Z","steps":["trace[1936834901] 'range keys from in-memory index tree'  (duration: 100.577045ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:08:53.922656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.441374ms","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-04-07T00:08:53.92287Z","caller":"traceutil/trace.go:171","msg":"trace[446288327] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1811; }","duration":"101.712439ms","start":"2026-04-07T00:08:53.821128Z","end":"2026-04-07T00:08:53.92284Z","steps":["trace[446288327] 'range keys from in-memory index tree'  (duration: 101.126707ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:08:53.922938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.114226ms","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-04-07T00:08:53.922971Z","caller":"traceutil/trace.go:171","msg":"trace[40140773] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1811; }","duration":"100.211008ms","start":"2026-04-07T00:08:53.822752Z","end":"2026-04-07T00:08:53.922963Z","steps":["trace[40140773] 'range keys from in-memory index tree'  (duration: 99.999243ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:09:02.091662Z","caller":"traceutil/trace.go:171","msg":"trace[514812370] linearizableReadLoop","detail":"{readStateIndex:1959; appliedIndex:1958; }","duration":"423.35122ms","start":"2026-04-07T00:09:01.668263Z","end":"2026-04-07T00:09:02.091615Z","steps":["trace[514812370] 'read index received'  (duration: 396.100969ms)","trace[514812370] 'applied index is now lower than readState.Index'  (duration: 27.248431ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:09:02.091718Z","caller":"traceutil/trace.go:171","msg":"trace[663636663] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"211.082138ms","start":"2026-04-07T00:09:01.880598Z","end":"2026-04-07T00:09:02.09168Z","steps":["trace[663636663] 'process raft request'  (duration: 210.900504ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:09:02.091989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.697217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"}
{"level":"warn","ts":"2026-04-07T00:09:02.092053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.691785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"}
{"level":"info","ts":"2026-04-07T00:09:02.092065Z","caller":"traceutil/trace.go:171","msg":"trace[1107275551] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1886; }","duration":"423.86186ms","start":"2026-04-07T00:09:01.668179Z","end":"2026-04-07T00:09:02.092041Z","steps":["trace[1107275551] 'agreement among raft nodes before linearized reading'  (duration: 423.622406ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:09:02.092097Z","caller":"traceutil/trace.go:171","msg":"trace[457287157] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1886; }","duration":"108.807487ms","start":"2026-04-07T00:09:01.983276Z","end":"2026-04-07T00:09:02.092083Z","steps":["trace[457287157] 'agreement among raft nodes before linearized reading'  (duration: 108.620074ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:09:02.092065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.702913ms","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":"warn","ts":"2026-04-07T00:09:02.092161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:09:01.668155Z","time spent":"423.983393ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"warn","ts":"2026-04-07T00:09:02.092167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.370875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11982"}
{"level":"info","ts":"2026-04-07T00:09:02.092304Z","caller":"traceutil/trace.go:171","msg":"trace[1598894132] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1886; }","duration":"416.573908ms","start":"2026-04-07T00:09:01.675707Z","end":"2026-04-07T00:09:02.092281Z","steps":["trace[1598894132] 'agreement among raft nodes before linearized reading'  (duration: 416.191341ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:09:02.092216Z","caller":"traceutil/trace.go:171","msg":"trace[1634533318] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1886; }","duration":"116.870047ms","start":"2026-04-07T00:09:01.975325Z","end":"2026-04-07T00:09:02.092195Z","steps":["trace[1634533318] 'agreement among raft nodes before linearized reading'  (duration: 116.616601ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:09:02.092336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.588401ms","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-04-07T00:09:02.092424Z","caller":"traceutil/trace.go:171","msg":"trace[1764373218] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1886; }","duration":"128.739644ms","start":"2026-04-07T00:09:01.963663Z","end":"2026-04-07T00:09:02.092403Z","steps":["trace[1764373218] 'agreement among raft nodes before linearized reading'  (duration: 128.421877ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:09:02.092558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:09:01.67568Z","time spent":"416.702151ms","remote":"127.0.0.1:41220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12004,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "}
{"level":"info","ts":"2026-04-07T00:09:06.336119Z","caller":"traceutil/trace.go:171","msg":"trace[152739185] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"134.060929ms","start":"2026-04-07T00:09:06.202033Z","end":"2026-04-07T00:09:06.336094Z","steps":["trace[152739185] 'process raft request'  (duration: 133.915976ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:09:06.337032Z","caller":"traceutil/trace.go:171","msg":"trace[1032176947] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"133.27477ms","start":"2026-04-07T00:09:06.203741Z","end":"2026-04-07T00:09:06.337016Z","steps":["trace[1032176947] 'process raft request'  (duration: 133.088096ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:09:06.337018Z","caller":"traceutil/trace.go:171","msg":"trace[1777193714] linearizableReadLoop","detail":"{readStateIndex:1986; appliedIndex:1984; }","duration":"132.953544ms","start":"2026-04-07T00:09:06.20404Z","end":"2026-04-07T00:09:06.336994Z","steps":["trace[1777193714] 'read index received'  (duration: 131.907341ms)","trace[1777193714] 'applied index is now lower than readState.Index'  (duration: 1.045003ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:09:06.337229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.178468ms","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-04-07T00:09:06.337264Z","caller":"traceutil/trace.go:171","msg":"trace[1456316174] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1913; }","duration":"133.252831ms","start":"2026-04-07T00:09:06.204003Z","end":"2026-04-07T00:09:06.337255Z","steps":["trace[1456316174] 'agreement among raft nodes before linearized reading'  (duration: 133.117398ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:09:06.337307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.062282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"}
{"level":"info","ts":"2026-04-07T00:09:06.337381Z","caller":"traceutil/trace.go:171","msg":"trace[944807256] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1913; }","duration":"131.179565ms","start":"2026-04-07T00:09:06.206189Z","end":"2026-04-07T00:09:06.337369Z","steps":["trace[944807256] 'agreement among raft nodes before linearized reading'  (duration: 130.96772ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:09:38.571569Z","caller":"traceutil/trace.go:171","msg":"trace[1034408295] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"117.699545ms","start":"2026-04-07T00:09:38.453816Z","end":"2026-04-07T00:09:38.571515Z","steps":["trace[1034408295] 'process raft request'  (duration: 117.45799ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:09:38.866567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.811857ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4831409558508154929 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:2091 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-07T00:09:38.866788Z","caller":"traceutil/trace.go:171","msg":"trace[1608824758] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"289.972742ms","start":"2026-04-07T00:09:38.576768Z","end":"2026-04-07T00:09:38.866741Z","steps":["trace[1608824758] 'process raft request'  (duration: 158.613503ms)","trace[1608824758] 'compare'  (duration: 130.4993ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:09:38.867322Z","caller":"traceutil/trace.go:171","msg":"trace[879835275] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"290.277399ms","start":"2026-04-07T00:09:38.577016Z","end":"2026-04-07T00:09:38.867294Z","steps":["trace[879835275] 'process raft request'  (duration: 290.099996ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:09:38.867716Z","caller":"traceutil/trace.go:171","msg":"trace[1102594281] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"290.319101ms","start":"2026-04-07T00:09:38.577363Z","end":"2026-04-07T00:09:38.867682Z","steps":["trace[1102594281] 'process raft request'  (duration: 289.891281ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:09:38.86798Z","caller":"traceutil/trace.go:171","msg":"trace[2111108879] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"290.461734ms","start":"2026-04-07T00:09:38.577499Z","end":"2026-04-07T00:09:38.867961Z","steps":["trace[2111108879] 'process raft request'  (duration: 290.019664ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:11:01.485606Z","caller":"traceutil/trace.go:171","msg":"trace[1117828279] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"121.409366ms","start":"2026-04-07T00:11:01.36416Z","end":"2026-04-07T00:11:01.485569Z","steps":["trace[1117828279] 'process raft request'  (duration: 121.17892ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:11:01.486057Z","caller":"traceutil/trace.go:171","msg":"trace[1552874095] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"121.56146ms","start":"2026-04-07T00:11:01.364403Z","end":"2026-04-07T00:11:01.485964Z","steps":["trace[1552874095] 'process raft request'  (duration: 121.12394ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:11:07.665959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.836268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"}
{"level":"warn","ts":"2026-04-07T00:11:07.666091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.765851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"}
{"level":"warn","ts":"2026-04-07T00:11:07.666137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.635641ms","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-04-07T00:11:07.666168Z","caller":"traceutil/trace.go:171","msg":"trace[264804346] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2774; }","duration":"198.109084ms","start":"2026-04-07T00:11:07.468025Z","end":"2026-04-07T00:11:07.666134Z","steps":["trace[264804346] 'range keys from in-memory index tree'  (duration: 197.565422ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:11:07.666343Z","caller":"traceutil/trace.go:171","msg":"trace[1786773712] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2774; }","duration":"146.904974ms","start":"2026-04-07T00:11:07.519262Z","end":"2026-04-07T00:11:07.666167Z","steps":["trace[1786773712] 'range keys from in-memory index tree'  (duration: 146.562527ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:11:07.666464Z","caller":"traceutil/trace.go:171","msg":"trace[1971750224] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2774; }","duration":"148.800015ms","start":"2026-04-07T00:11:07.517418Z","end":"2026-04-07T00:11:07.666218Z","steps":["trace[1971750224] 'range keys from in-memory index tree'  (duration: 148.390086ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:15:08.968114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.311449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1ada3c34-3ab9-413c-bd61-62290bbf16ac\" ","response":"range_response_count:1 size:2694"}
{"level":"info","ts":"2026-04-07T00:15:08.968201Z","caller":"traceutil/trace.go:171","msg":"trace[2090667946] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1ada3c34-3ab9-413c-bd61-62290bbf16ac; range_end:; response_count:1; response_revision:4779; }","duration":"131.409311ms","start":"2026-04-07T00:15:08.836775Z","end":"2026-04-07T00:15:08.968185Z","steps":["trace[2090667946] 'range keys from in-memory index tree'  (duration: 131.258187ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:15:08.968389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.086473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"}
{"level":"info","ts":"2026-04-07T00:15:08.968468Z","caller":"traceutil/trace.go:171","msg":"trace[1021879094] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4779; }","duration":"131.185056ms","start":"2026-04-07T00:15:08.837267Z","end":"2026-04-07T00:15:08.968452Z","steps":["trace[1021879094] 'range keys from in-memory index tree'  (duration: 130.95081ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:15:08.973712Z","caller":"traceutil/trace.go:171","msg":"trace[1001235906] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"123.387076ms","start":"2026-04-07T00:15:08.850294Z","end":"2026-04-07T00:15:08.973681Z","steps":["trace[1001235906] 'process raft request'  (duration: 123.213772ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:15:09.002366Z","caller":"traceutil/trace.go:171","msg":"trace[2060546835] transaction","detail":"{read_only:false; response_revision:4781; number_of_response:1; }","duration":"149.473827ms","start":"2026-04-07T00:15:08.85286Z","end":"2026-04-07T00:15:09.002334Z","steps":["trace[2060546835] 'process raft request'  (duration: 149.249762ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:15:15.407611Z","caller":"traceutil/trace.go:171","msg":"trace[1112940671] linearizableReadLoop","detail":"{readStateIndex:5112; appliedIndex:5110; }","duration":"128.34419ms","start":"2026-04-07T00:15:15.279239Z","end":"2026-04-07T00:15:15.407584Z","steps":["trace[1112940671] 'read index received'  (duration: 35.414885ms)","trace[1112940671] 'applied index is now lower than readState.Index'  (duration: 92.928025ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:15:15.407712Z","caller":"traceutil/trace.go:171","msg":"trace[118259498] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"148.018424ms","start":"2026-04-07T00:15:15.259657Z","end":"2026-04-07T00:15:15.407675Z","steps":["trace[118259498] 'process raft request'  (duration: 127.981581ms)","trace[118259498] 'compare'  (duration: 19.811248ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:15:15.407793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.533745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-07T00:15:15.407884Z","caller":"traceutil/trace.go:171","msg":"trace[2110668520] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4921; }","duration":"128.692758ms","start":"2026-04-07T00:15:15.279173Z","end":"2026-04-07T00:15:15.407866Z","steps":["trace[2110668520] 'agreement among raft nodes before linearized reading'  (duration: 128.544635ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:15:18.53916Z","caller":"traceutil/trace.go:171","msg":"trace[1899782220] transaction","detail":"{read_only:false; response_revision:5057; number_of_response:1; }","duration":"113.003485ms","start":"2026-04-07T00:15:18.426121Z","end":"2026-04-07T00:15:18.539125Z","steps":["trace[1899782220] 'process raft request'  (duration: 112.602366ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:15:18.76326Z","caller":"traceutil/trace.go:171","msg":"trace[2137674072] transaction","detail":"{read_only:false; response_revision:5058; number_of_response:1; }","duration":"213.517514ms","start":"2026-04-07T00:15:18.549723Z","end":"2026-04-07T00:15:18.763241Z","steps":["trace[2137674072] 'process raft request'  (duration: 213.402102ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:15:18.773605Z","caller":"traceutil/trace.go:171","msg":"trace[992444901] transaction","detail":"{read_only:false; response_revision:5059; number_of_response:1; }","duration":"126.716372ms","start":"2026-04-07T00:15:18.646872Z","end":"2026-04-07T00:15:18.773588Z","steps":["trace[992444901] 'process raft request'  (duration: 126.462476ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:15:37.313515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.314615ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4831409558508162866 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:5337 > 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:16"}
{"level":"info","ts":"2026-04-07T00:15:37.31361Z","caller":"traceutil/trace.go:171","msg":"trace[989011715] linearizableReadLoop","detail":"{readStateIndex:5624; appliedIndex:5623; }","duration":"102.031892ms","start":"2026-04-07T00:15:37.211565Z","end":"2026-04-07T00:15:37.313597Z","steps":["trace[989011715] 'read index received'  (duration: 107.072µs)","trace[989011715] 'applied index is now lower than readState.Index'  (duration: 101.92295ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:15:37.313658Z","caller":"traceutil/trace.go:171","msg":"trace[1273642382] transaction","detail":"{read_only:false; response_revision:5419; number_of_response:1; }","duration":"120.582715ms","start":"2026-04-07T00:15:37.193069Z","end":"2026-04-07T00:15:37.313652Z","steps":["trace[1273642382] 'process raft request'  (duration: 10.051695ms)","trace[1273642382] 'compare'  (duration: 110.190082ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:15:37.313887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.313948ms","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-04-07T00:15:37.31465Z","caller":"traceutil/trace.go:171","msg":"trace[1685648510] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5419; }","duration":"103.126397ms","start":"2026-04-07T00:15:37.211508Z","end":"2026-04-07T00:15:37.314635Z","steps":["trace[1685648510] 'agreement among raft nodes before linearized reading'  (duration: 102.244466ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:15:37.562197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.840978ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4831409558508162867 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls-7tw7g\" mod_revision:0 > success:<request_put:<key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls-7tw7g\" value_size:2489 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-04-07T00:15:37.562483Z","caller":"traceutil/trace.go:171","msg":"trace[1516689889] transaction","detail":"{read_only:false; response_revision:5421; number_of_response:1; }","duration":"241.920937ms","start":"2026-04-07T00:15:37.320539Z","end":"2026-04-07T00:15:37.56246Z","steps":["trace[1516689889] 'process raft request'  (duration: 241.838546ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:15:37.562487Z","caller":"traceutil/trace.go:171","msg":"trace[1899715518] linearizableReadLoop","detail":"{readStateIndex:5625; appliedIndex:5624; }","duration":"248.823659ms","start":"2026-04-07T00:15:37.313624Z","end":"2026-04-07T00:15:37.562448Z","steps":["trace[1899715518] 'read index received'  (duration: 119.651294ms)","trace[1899715518] 'applied index is now lower than readState.Index'  (duration: 129.168155ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:15:37.562469Z","caller":"traceutil/trace.go:171","msg":"trace[551895530] transaction","detail":"{read_only:false; response_revision:5420; number_of_response:1; }","duration":"268.191872ms","start":"2026-04-07T00:15:37.294236Z","end":"2026-04-07T00:15:37.562428Z","steps":["trace[551895530] 'process raft request'  (duration: 139.026186ms)","trace[551895530] 'compare'  (duration: 128.607243ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:15:37.562722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.903618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"warn","ts":"2026-04-07T00:15:37.562783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.715108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c39e102f-0f63-44e5-8ad1-4f8c89f7035a\" ","response":"range_response_count:1 size:3646"}
{"level":"warn","ts":"2026-04-07T00:15:37.562806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.896209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7jm5m\" ","response":"range_response_count:1 size:6531"}
{"level":"info","ts":"2026-04-07T00:15:37.56281Z","caller":"traceutil/trace.go:171","msg":"trace[1371316764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5421; }","duration":"290.085402ms","start":"2026-04-07T00:15:37.27271Z","end":"2026-04-07T00:15:37.562795Z","steps":["trace[1371316764] 'agreement among raft nodes before linearized reading'  (duration: 289.759255ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:15:37.562823Z","caller":"traceutil/trace.go:171","msg":"trace[778977995] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c39e102f-0f63-44e5-8ad1-4f8c89f7035a; range_end:; response_count:1; response_revision:5421; }","duration":"129.765449ms","start":"2026-04-07T00:15:37.433045Z","end":"2026-04-07T00:15:37.562811Z","steps":["trace[778977995] 'agreement among raft nodes before linearized reading'  (duration: 129.610506ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:15:37.562851Z","caller":"traceutil/trace.go:171","msg":"trace[1147639289] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7jm5m; range_end:; response_count:1; response_revision:5421; }","duration":"149.983411ms","start":"2026-04-07T00:15:37.41285Z","end":"2026-04-07T00:15:37.562833Z","steps":["trace[1147639289] 'agreement among raft nodes before linearized reading'  (duration: 149.772806ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:15:37.562821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.679953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"warn","ts":"2026-04-07T00:15:37.562841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.826759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"}
{"level":"info","ts":"2026-04-07T00:15:37.562926Z","caller":"traceutil/trace.go:171","msg":"trace[784147587] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5421; }","duration":"349.810246ms","start":"2026-04-07T00:15:37.213085Z","end":"2026-04-07T00:15:37.562895Z","steps":["trace[784147587] 'agreement among raft nodes before linearized reading'  (duration: 349.497729ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:15:37.562954Z","caller":"traceutil/trace.go:171","msg":"trace[1516362444] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5421; }","duration":"285.001293ms","start":"2026-04-07T00:15:37.277924Z","end":"2026-04-07T00:15:37.562925Z","steps":["trace[1516362444] 'agreement among raft nodes before linearized reading'  (duration: 284.581133ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:15:37.563015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:15:37.213065Z","time spent":"349.915119ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-04-07T00:15:37.56276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.096823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-04-07T00:15:37.563236Z","caller":"traceutil/trace.go:171","msg":"trace[1111837764] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:5421; }","duration":"347.620366ms","start":"2026-04-07T00:15:37.21559Z","end":"2026-04-07T00:15:37.563211Z","steps":["trace[1111837764] 'agreement among raft nodes before linearized reading'  (duration: 346.9181ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:15:37.563356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:15:37.215581Z","time spent":"347.751528ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":6,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "}
{"level":"info","ts":"2026-04-07T00:15:37.838997Z","caller":"traceutil/trace.go:171","msg":"trace[1172058697] transaction","detail":"{read_only:false; response_revision:5423; number_of_response:1; }","duration":"271.447837ms","start":"2026-04-07T00:15:37.567522Z","end":"2026-04-07T00:15:37.83897Z","steps":["trace[1172058697] 'process raft request'  (duration: 206.347357ms)","trace[1172058697] 'compare'  (duration: 64.894286ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:15:37.839391Z","caller":"traceutil/trace.go:171","msg":"trace[1767878341] transaction","detail":"{read_only:false; response_revision:5424; number_of_response:1; }","duration":"271.211732ms","start":"2026-04-07T00:15:37.568142Z","end":"2026-04-07T00:15:37.839354Z","steps":["trace[1767878341] 'process raft request'  (duration: 270.776582ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:15:37.839514Z","caller":"traceutil/trace.go:171","msg":"trace[1422023276] linearizableReadLoop","detail":"{readStateIndex:5630; appliedIndex:5627; }","duration":"270.537596ms","start":"2026-04-07T00:15:37.568945Z","end":"2026-04-07T00:15:37.839482Z","steps":["trace[1422023276] 'read index received'  (duration: 19.270539ms)","trace[1422023276] 'applied index is now lower than readState.Index'  (duration: 251.266007ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:15:37.839554Z","caller":"traceutil/trace.go:171","msg":"trace[1096171685] transaction","detail":"{read_only:false; response_revision:5426; number_of_response:1; }","duration":"266.219925ms","start":"2026-04-07T00:15:37.573292Z","end":"2026-04-07T00:15:37.839512Z","steps":["trace[1096171685] 'process raft request'  (duration: 266.122853ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:15:37.839547Z","caller":"traceutil/trace.go:171","msg":"trace[2033139635] transaction","detail":"{read_only:false; response_revision:5425; number_of_response:1; }","duration":"270.932586ms","start":"2026-04-07T00:15:37.568574Z","end":"2026-04-07T00:15:37.839506Z","steps":["trace[2033139635] 'process raft request'  (duration: 270.68728ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:15:37.8398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.939469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"}
{"level":"info","ts":"2026-04-07T00:15:37.839846Z","caller":"traceutil/trace.go:171","msg":"trace[1283034226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5426; }","duration":"260.01862ms","start":"2026-04-07T00:15:37.579817Z","end":"2026-04-07T00:15:37.839835Z","steps":["trace[1283034226] 'agreement among raft nodes before linearized reading'  (duration: 259.840626ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:15:37.839791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.815683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"}
{"level":"info","ts":"2026-04-07T00:15:37.83991Z","caller":"traceutil/trace.go:171","msg":"trace[31947672] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5426; }","duration":"270.987627ms","start":"2026-04-07T00:15:37.568896Z","end":"2026-04-07T00:15:37.839884Z","steps":["trace[31947672] 'agreement among raft nodes before linearized reading'  (duration: 270.67901ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:15:50.263264Z","caller":"traceutil/trace.go:171","msg":"trace[1465344319] transaction","detail":"{read_only:false; response_revision:5602; number_of_response:1; }","duration":"108.932873ms","start":"2026-04-07T00:15:50.154295Z","end":"2026-04-07T00:15:50.263228Z","steps":["trace[1465344319] 'process raft request'  (duration: 108.669787ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:15:50.48797Z","caller":"traceutil/trace.go:171","msg":"trace[1397204444] transaction","detail":"{read_only:false; response_revision:5604; number_of_response:1; }","duration":"122.268944ms","start":"2026-04-07T00:15:50.365667Z","end":"2026-04-07T00:15:50.487936Z","steps":["trace[1397204444] 'process raft request'  (duration: 108.743049ms)","trace[1397204444] 'compare'  (duration: 13.360232ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:15:50.505359Z","caller":"traceutil/trace.go:171","msg":"trace[1263988966] transaction","detail":"{read_only:false; response_revision:5605; number_of_response:1; }","duration":"139.077826ms","start":"2026-04-07T00:15:50.366247Z","end":"2026-04-07T00:15:50.505325Z","steps":["trace[1263988966] 'process raft request'  (duration: 138.81083ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:15:50.505662Z","caller":"traceutil/trace.go:171","msg":"trace[712872228] transaction","detail":"{read_only:false; response_revision:5606; number_of_response:1; }","duration":"139.306071ms","start":"2026-04-07T00:15:50.36631Z","end":"2026-04-07T00:15:50.505616Z","steps":["trace[712872228] 'process raft request'  (duration: 138.972053ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:15:50.505777Z","caller":"traceutil/trace.go:171","msg":"trace[1985439985] transaction","detail":"{read_only:false; response_revision:5607; number_of_response:1; }","duration":"139.19548ms","start":"2026-04-07T00:15:50.366552Z","end":"2026-04-07T00:15:50.505747Z","steps":["trace[1985439985] 'process raft request'  (duration: 139.081117ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:16:43.513431Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2971}
{"level":"info","ts":"2026-04-07T00:16:43.58111Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2971,"took":"65.994978ms","hash":1838683049,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":33517568,"current-db-size-in-use":"34 MB"}
{"level":"info","ts":"2026-04-07T00:16:43.581489Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1838683049,"revision":2971,"compact-revision":-1}
{"level":"info","ts":"2026-04-07T00:21:30.819169Z","caller":"traceutil/trace.go:171","msg":"trace[1024344152] linearizableReadLoop","detail":"{readStateIndex:8497; appliedIndex:8496; }","duration":"154.995937ms","start":"2026-04-07T00:21:30.664147Z","end":"2026-04-07T00:21:30.819143Z","steps":["trace[1024344152] 'read index received'  (duration: 154.830143ms)","trace[1024344152] 'applied index is now lower than readState.Index'  (duration: 164.714µs)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:21:30.819277Z","caller":"traceutil/trace.go:171","msg":"trace[963557093] transaction","detail":"{read_only:false; response_revision:8208; number_of_response:1; }","duration":"159.789551ms","start":"2026-04-07T00:21:30.659449Z","end":"2026-04-07T00:21:30.819238Z","steps":["trace[963557093] 'process raft request'  (duration: 159.507054ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:21:30.819351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.186242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-07T00:21:30.819396Z","caller":"traceutil/trace.go:171","msg":"trace[1270464290] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:8208; }","duration":"155.280763ms","start":"2026-04-07T00:21:30.664103Z","end":"2026-04-07T00:21:30.819384Z","steps":["trace[1270464290] 'agreement among raft nodes before linearized reading'  (duration: 155.189871ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:21:30.81952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.014395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"}
{"level":"info","ts":"2026-04-07T00:21:30.819608Z","caller":"traceutil/trace.go:171","msg":"trace[471747859] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8208; }","duration":"126.116878ms","start":"2026-04-07T00:21:30.69347Z","end":"2026-04-07T00:21:30.819587Z","steps":["trace[471747859] 'agreement among raft nodes before linearized reading'  (duration: 125.892213ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:21:43.602523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.643902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"warn","ts":"2026-04-07T00:21:43.602568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.217104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"}
{"level":"info","ts":"2026-04-07T00:21:43.602613Z","caller":"traceutil/trace.go:171","msg":"trace[838622240] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8274; }","duration":"105.784965ms","start":"2026-04-07T00:21:43.496809Z","end":"2026-04-07T00:21:43.602594Z","steps":["trace[838622240] 'range keys from in-memory index tree'  (duration: 105.450548ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:21:43.602658Z","caller":"traceutil/trace.go:171","msg":"trace[40453738] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8274; }","duration":"330.340237ms","start":"2026-04-07T00:21:43.272297Z","end":"2026-04-07T00:21:43.602637Z","steps":["trace[40453738] 'range keys from in-memory index tree'  (duration: 330.03278ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:21:43.602702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:21:43.272282Z","time spent":"330.410839ms","remote":"127.0.0.1:41220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "}
{"level":"warn","ts":"2026-04-07T00:21:43.602754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.545121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"}
{"level":"info","ts":"2026-04-07T00:21:43.602831Z","caller":"traceutil/trace.go:171","msg":"trace[1261968039] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8274; }","duration":"262.661892ms","start":"2026-04-07T00:21:43.340149Z","end":"2026-04-07T00:21:43.602811Z","steps":["trace[1261968039] 'range keys from in-memory index tree'  (duration: 262.379527ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:21:43.652495Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6055}
{"level":"info","ts":"2026-04-07T00:21:43.92026Z","caller":"traceutil/trace.go:171","msg":"trace[162395049] linearizableReadLoop","detail":"{readStateIndex:8574; appliedIndex:8570; }","duration":"109.512476ms","start":"2026-04-07T00:21:43.810724Z","end":"2026-04-07T00:21:43.920237Z","steps":["trace[162395049] 'read index received'  (duration: 4.405306ms)","trace[162395049] 'applied index is now lower than readState.Index'  (duration: 105.10608ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:21:43.920419Z","caller":"traceutil/trace.go:171","msg":"trace[1549420075] transaction","detail":"{read_only:false; response_revision:8281; number_of_response:1; }","duration":"110.704231ms","start":"2026-04-07T00:21:43.809641Z","end":"2026-04-07T00:21:43.920345Z","steps":["trace[1549420075] 'process raft request'  (duration: 110.556878ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:21:43.920283Z","caller":"traceutil/trace.go:171","msg":"trace[1725255543] transaction","detail":"{read_only:false; response_revision:8279; number_of_response:1; }","duration":"110.765103ms","start":"2026-04-07T00:21:43.809468Z","end":"2026-04-07T00:21:43.920233Z","steps":["trace[1725255543] 'process raft request'  (duration: 49.60556ms)","trace[1725255543] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-controller-manager; req_size:488; } (duration: 60.850106ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:21:43.920309Z","caller":"traceutil/trace.go:171","msg":"trace[828614526] transaction","detail":"{read_only:false; response_revision:8280; number_of_response:1; }","duration":"110.672941ms","start":"2026-04-07T00:21:43.809605Z","end":"2026-04-07T00:21:43.920278Z","steps":["trace[828614526] 'process raft request'  (duration: 110.524157ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:21:43.920922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.180821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223820"}
{"level":"info","ts":"2026-04-07T00:21:43.921007Z","caller":"traceutil/trace.go:171","msg":"trace[1137597158] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8281; }","duration":"110.301933ms","start":"2026-04-07T00:21:43.810686Z","end":"2026-04-07T00:21:43.920988Z","steps":["trace[1137597158] 'agreement among raft nodes before linearized reading'  (duration: 109.734811ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:21:44.038524Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6055,"took":"383.025254ms","hash":2268219253,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":23232512,"current-db-size-in-use":"23 MB"}
{"level":"info","ts":"2026-04-07T00:21:44.038587Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2268219253,"revision":6055,"compact-revision":2971}
{"level":"info","ts":"2026-04-07T00:22:11.237499Z","caller":"traceutil/trace.go:171","msg":"trace[1658756688] linearizableReadLoop","detail":"{readStateIndex:8777; appliedIndex:8776; }","duration":"214.070165ms","start":"2026-04-07T00:22:11.023372Z","end":"2026-04-07T00:22:11.237442Z","steps":["trace[1658756688] 'read index received'  (duration: 213.816998ms)","trace[1658756688] 'applied index is now lower than readState.Index'  (duration: 251.596µs)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:22:11.237547Z","caller":"traceutil/trace.go:171","msg":"trace[447890642] transaction","detail":"{read_only:false; response_revision:8476; number_of_response:1; }","duration":"248.469944ms","start":"2026-04-07T00:22:10.989048Z","end":"2026-04-07T00:22:11.237518Z","steps":["trace[447890642] 'process raft request'  (duration: 248.150536ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:22:11.237881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.500383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"}
{"level":"info","ts":"2026-04-07T00:22:11.238Z","caller":"traceutil/trace.go:171","msg":"trace[904325667] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8476; }","duration":"214.626496ms","start":"2026-04-07T00:22:11.023312Z","end":"2026-04-07T00:22:11.237938Z","steps":["trace[904325667] 'agreement among raft nodes before linearized reading'  (duration: 214.438052ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:22:23.350916Z","caller":"traceutil/trace.go:171","msg":"trace[1899466552] linearizableReadLoop","detail":"{readStateIndex:8883; appliedIndex:8882; }","duration":"183.343345ms","start":"2026-04-07T00:22:23.167545Z","end":"2026-04-07T00:22:23.350888Z","steps":["trace[1899466552] 'read index received'  (duration: 183.233453ms)","trace[1899466552] 'applied index is now lower than readState.Index'  (duration: 108.932µs)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:22:23.35107Z","caller":"traceutil/trace.go:171","msg":"trace[1416706549] transaction","detail":"{read_only:false; response_revision:8576; number_of_response:1; }","duration":"183.53178ms","start":"2026-04-07T00:22:23.1675Z","end":"2026-04-07T00:22:23.351032Z","steps":["trace[1416706549] 'process raft request'  (duration: 183.232113ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:22:23.351175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.605771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"}
{"level":"info","ts":"2026-04-07T00:22:23.351452Z","caller":"traceutil/trace.go:171","msg":"trace[619450893] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:8576; }","duration":"183.893077ms","start":"2026-04-07T00:22:23.167541Z","end":"2026-04-07T00:22:23.351434Z","steps":["trace[619450893] 'agreement among raft nodes before linearized reading'  (duration: 183.493839ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:22:23.360353Z","caller":"traceutil/trace.go:171","msg":"trace[1663110733] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8577; }","duration":"192.494784ms","start":"2026-04-07T00:22:23.167842Z","end":"2026-04-07T00:22:23.360337Z","steps":["trace[1663110733] 'process raft request'  (duration: 192.166517ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:22:23.960536Z","caller":"traceutil/trace.go:171","msg":"trace[1673805494] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"106.635493ms","start":"2026-04-07T00:22:23.853865Z","end":"2026-04-07T00:22:23.9605Z","steps":["trace[1673805494] 'process raft request'  (duration: 73.172274ms)","trace[1673805494] 'compare'  (duration: 33.278475ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:22:24.223231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.616217ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4831409558508171443 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:8563 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-07T00:22:24.223353Z","caller":"traceutil/trace.go:171","msg":"trace[1465085431] linearizableReadLoop","detail":"{readStateIndex:8893; appliedIndex:8892; }","duration":"184.00279ms","start":"2026-04-07T00:22:24.039332Z","end":"2026-04-07T00:22:24.223335Z","steps":["trace[1465085431] 'read index received'  (duration: 35.169377ms)","trace[1465085431] 'applied index is now lower than readState.Index'  (duration: 148.831683ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:22:24.223433Z","caller":"traceutil/trace.go:171","msg":"trace[2065289876] transaction","detail":"{read_only:false; response_revision:8586; number_of_response:1; }","duration":"258.649874ms","start":"2026-04-07T00:22:23.964766Z","end":"2026-04-07T00:22:24.223416Z","steps":["trace[2065289876] 'process raft request'  (duration: 109.759211ms)","trace[2065289876] 'compare'  (duration: 148.425814ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:22:24.223555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.196274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-04-07T00:22:24.223636Z","caller":"traceutil/trace.go:171","msg":"trace[1625806399] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:8586; }","duration":"184.342347ms","start":"2026-04-07T00:22:24.039273Z","end":"2026-04-07T00:22:24.223615Z","steps":["trace[1625806399] 'agreement among raft nodes before linearized reading'  (duration: 184.149043ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:22:24.247017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.738754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"}
{"level":"info","ts":"2026-04-07T00:22:24.247084Z","caller":"traceutil/trace.go:171","msg":"trace[466964661] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8587; }","duration":"183.824595ms","start":"2026-04-07T00:22:24.063242Z","end":"2026-04-07T00:22:24.247066Z","steps":["trace[466964661] 'agreement among raft nodes before linearized reading'  (duration: 183.617301ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:22:24.247222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.443047ms","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-04-07T00:22:24.247478Z","caller":"traceutil/trace.go:171","msg":"trace[292365255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8587; }","duration":"110.707193ms","start":"2026-04-07T00:22:24.136755Z","end":"2026-04-07T00:22:24.247462Z","steps":["trace[292365255] 'agreement among raft nodes before linearized reading'  (duration: 110.341245ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:22:24.247494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.360704ms","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-04-07T00:22:24.247535Z","caller":"traceutil/trace.go:171","msg":"trace[1709906491] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8587; }","duration":"132.438386ms","start":"2026-04-07T00:22:24.115085Z","end":"2026-04-07T00:22:24.247524Z","steps":["trace[1709906491] 'agreement among raft nodes before linearized reading'  (duration: 132.341244ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:22:24.247693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.380015ms","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-04-07T00:22:24.247724Z","caller":"traceutil/trace.go:171","msg":"trace[1387025634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8587; }","duration":"110.430356ms","start":"2026-04-07T00:22:24.137286Z","end":"2026-04-07T00:22:24.247717Z","steps":["trace[1387025634] 'agreement among raft nodes before linearized reading'  (duration: 110.322944ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:22:24.928388Z","caller":"traceutil/trace.go:171","msg":"trace[1669646567] transaction","detail":"{read_only:false; response_revision:8595; number_of_response:1; }","duration":"165.592787ms","start":"2026-04-07T00:22:24.762768Z","end":"2026-04-07T00:22:24.928361Z","steps":["trace[1669646567] 'process raft request'  (duration: 165.364272ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:23:13.110235Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024b4.wal"}
{"level":"info","ts":"2026-04-07T00:23:25.693238Z","caller":"traceutil/trace.go:171","msg":"trace[95261027] transaction","detail":"{read_only:false; response_revision:9225; number_of_response:1; }","duration":"161.129624ms","start":"2026-04-07T00:23:25.532062Z","end":"2026-04-07T00:23:25.693191Z","steps":["trace[95261027] 'process raft request'  (duration: 160.845828ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:23:25.700185Z","caller":"traceutil/trace.go:171","msg":"trace[1421668748] transaction","detail":"{read_only:false; response_revision:9226; number_of_response:1; }","duration":"112.599044ms","start":"2026-04-07T00:23:25.58756Z","end":"2026-04-07T00:23:25.700159Z","steps":["trace[1421668748] 'process raft request'  (duration: 112.40632ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:23:35.554833Z","caller":"traceutil/trace.go:171","msg":"trace[314932718] transaction","detail":"{read_only:false; response_revision:9307; number_of_response:1; }","duration":"113.524074ms","start":"2026-04-07T00:23:35.441272Z","end":"2026-04-07T00:23:35.554796Z","steps":["trace[314932718] 'process raft request'  (duration: 113.33328ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:24:22.680883Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e98569549cc430c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-04-07T00:24:22.685714Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-04-07T00:24:22.685792Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"info","ts":"2026-04-07T00:26:43.659383Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8275}
{"level":"info","ts":"2026-04-07T00:26:43.749794Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8275,"took":"88.002551ms","hash":2101763825,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":26300416,"current-db-size-in-use":"26 MB"}
{"level":"info","ts":"2026-04-07T00:26:43.749876Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2101763825,"revision":8275,"compact-revision":6055}
{"level":"info","ts":"2026-04-07T00:27:03.763555Z","caller":"traceutil/trace.go:171","msg":"trace[1857165638] transaction","detail":"{read_only:false; response_revision:11069; number_of_response:1; }","duration":"112.558241ms","start":"2026-04-07T00:27:03.650965Z","end":"2026-04-07T00:27:03.763523Z","steps":["trace[1857165638] 'process raft request'  (duration: 112.356466ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:27:03.94287Z","caller":"traceutil/trace.go:171","msg":"trace[2127452656] transaction","detail":"{read_only:false; response_revision:11070; number_of_response:1; }","duration":"174.61548ms","start":"2026-04-07T00:27:03.768215Z","end":"2026-04-07T00:27:03.94283Z","steps":["trace[2127452656] 'process raft request'  (duration: 149.13776ms)","trace[2127452656] 'compare'  (duration: 25.030341ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:27:03.943119Z","caller":"traceutil/trace.go:171","msg":"trace[476912511] transaction","detail":"{read_only:false; response_revision:11071; number_of_response:1; }","duration":"174.527869ms","start":"2026-04-07T00:27:03.768559Z","end":"2026-04-07T00:27:03.943087Z","steps":["trace[476912511] 'process raft request'  (duration: 174.156911ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:27:03.943357Z","caller":"traceutil/trace.go:171","msg":"trace[1723924522] transaction","detail":"{read_only:false; response_revision:11072; number_of_response:1; }","duration":"174.759694ms","start":"2026-04-07T00:27:03.768568Z","end":"2026-04-07T00:27:03.943328Z","steps":["trace[1723924522] 'process raft request'  (duration: 174.438457ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:27:03.943735Z","caller":"traceutil/trace.go:171","msg":"trace[1997080136] linearizableReadLoop","detail":"{readStateIndex:11451; appliedIndex:11447; }","duration":"107.665205ms","start":"2026-04-07T00:27:03.836051Z","end":"2026-04-07T00:27:03.943716Z","steps":["trace[1997080136] 'read index received'  (duration: 81.440329ms)","trace[1997080136] 'applied index is now lower than readState.Index'  (duration: 26.223876ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:27:03.943837Z","caller":"traceutil/trace.go:171","msg":"trace[1452367158] transaction","detail":"{read_only:false; response_revision:11073; number_of_response:1; }","duration":"175.207114ms","start":"2026-04-07T00:27:03.76862Z","end":"2026-04-07T00:27:03.943827Z","steps":["trace[1452367158] 'process raft request'  (duration: 174.462778ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:27:03.94397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.89999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-07T00:27:03.944002Z","caller":"traceutil/trace.go:171","msg":"trace[336761673] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11073; }","duration":"108.026873ms","start":"2026-04-07T00:27:03.835965Z","end":"2026-04-07T00:27:03.943992Z","steps":["trace[336761673] 'agreement among raft nodes before linearized reading'  (duration: 107.955311ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:27:03.94415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.005923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-07T00:27:03.944181Z","caller":"traceutil/trace.go:171","msg":"trace[1456336882] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11073; }","duration":"108.054024ms","start":"2026-04-07T00:27:03.836119Z","end":"2026-04-07T00:27:03.944173Z","steps":["trace[1456336882] 'agreement among raft nodes before linearized reading'  (duration: 108.010593ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:27:03.944532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.342679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:668"}
{"level":"info","ts":"2026-04-07T00:27:03.944559Z","caller":"traceutil/trace.go:171","msg":"trace[1055611714] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11073; }","duration":"107.38682ms","start":"2026-04-07T00:27:03.837165Z","end":"2026-04-07T00:27:03.944551Z","steps":["trace[1055611714] 'agreement among raft nodes before linearized reading'  (duration: 107.278938ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:27:03.944721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.226178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"}
{"level":"info","ts":"2026-04-07T00:27:03.944755Z","caller":"traceutil/trace.go:171","msg":"trace[1230787317] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11073; }","duration":"108.276279ms","start":"2026-04-07T00:27:03.836471Z","end":"2026-04-07T00:27:03.944747Z","steps":["trace[1230787317] 'agreement among raft nodes before linearized reading'  (duration: 108.194328ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:27:03.944766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.507294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-07T00:27:03.944876Z","caller":"traceutil/trace.go:171","msg":"trace[1233714610] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11073; }","duration":"108.659657ms","start":"2026-04-07T00:27:03.8362Z","end":"2026-04-07T00:27:03.94486Z","steps":["trace[1233714610] 'agreement among raft nodes before linearized reading'  (duration: 108.518344ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:27:03.945431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.208439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-07T00:27:03.945469Z","caller":"traceutil/trace.go:171","msg":"trace[971790505] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11073; }","duration":"109.28317ms","start":"2026-04-07T00:27:03.836176Z","end":"2026-04-07T00:27:03.945459Z","steps":["trace[971790505] 'agreement among raft nodes before linearized reading'  (duration: 109.209329ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:31:43.665353Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10938}
{"level":"info","ts":"2026-04-07T00:31:43.748388Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10938,"took":"80.868727ms","hash":2289187771,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":28069888,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-04-07T00:31:43.74844Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2289187771,"revision":10938,"compact-revision":8275}
{"level":"info","ts":"2026-04-07T00:32:52.546767Z","caller":"traceutil/trace.go:171","msg":"trace[1235548497] transaction","detail":"{read_only:false; response_revision:13454; number_of_response:1; }","duration":"138.051936ms","start":"2026-04-07T00:32:52.408672Z","end":"2026-04-07T00:32:52.546724Z","steps":["trace[1235548497] 'process raft request'  (duration: 137.890102ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:33:28.197613Z","caller":"traceutil/trace.go:171","msg":"trace[1242661279] transaction","detail":"{read_only:false; response_revision:13658; number_of_response:1; }","duration":"444.796464ms","start":"2026-04-07T00:33:27.752789Z","end":"2026-04-07T00:33:28.197586Z","steps":["trace[1242661279] 'process raft request'  (duration: 444.635881ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:33:28.197797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:33:27.752766Z","time spent":"444.950788ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:13642 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-04-07T00:33:28.199011Z","caller":"traceutil/trace.go:171","msg":"trace[1310234118] linearizableReadLoop","detail":"{readStateIndex:14129; appliedIndex:14127; }","duration":"417.58976ms","start":"2026-04-07T00:33:27.7814Z","end":"2026-04-07T00:33:28.198989Z","steps":["trace[1310234118] 'read index received'  (duration: 416.008937ms)","trace[1310234118] 'applied index is now lower than readState.Index'  (duration: 1.579693ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:33:28.199127Z","caller":"traceutil/trace.go:171","msg":"trace[726305978] transaction","detail":"{read_only:false; response_revision:13660; number_of_response:1; }","duration":"280.791523ms","start":"2026-04-07T00:33:27.918298Z","end":"2026-04-07T00:33:28.199089Z","steps":["trace[726305978] 'process raft request'  (duration: 280.65344ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:33:28.199049Z","caller":"traceutil/trace.go:171","msg":"trace[1155620135] transaction","detail":"{read_only:false; response_revision:13659; number_of_response:1; }","duration":"444.410535ms","start":"2026-04-07T00:33:27.754613Z","end":"2026-04-07T00:33:28.199023Z","steps":["trace[1155620135] 'process raft request'  (duration: 444.195081ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:33:28.199275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.829885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"}
{"level":"info","ts":"2026-04-07T00:33:28.199379Z","caller":"traceutil/trace.go:171","msg":"trace[1878786072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13660; }","duration":"418.011539ms","start":"2026-04-07T00:33:27.781349Z","end":"2026-04-07T00:33:28.199361Z","steps":["trace[1878786072] 'agreement among raft nodes before linearized reading'  (duration: 417.750504ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:33:28.199417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:33:27.781327Z","time spent":"418.08001ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-04-07T00:33:28.199502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:33:27.754596Z","time spent":"444.64413ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:13643 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-04-07T00:33:28.199497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.719653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"}
{"level":"info","ts":"2026-04-07T00:33:28.199635Z","caller":"traceutil/trace.go:171","msg":"trace[2010191490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13660; }","duration":"417.892767ms","start":"2026-04-07T00:33:27.781704Z","end":"2026-04-07T00:33:28.199597Z","steps":["trace[2010191490] 'agreement among raft nodes before linearized reading'  (duration: 417.613491ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:33:28.199645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.879145ms","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-04-07T00:33:28.199694Z","caller":"traceutil/trace.go:171","msg":"trace[1729251241] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13660; }","duration":"411.956677ms","start":"2026-04-07T00:33:27.787722Z","end":"2026-04-07T00:33:28.199679Z","steps":["trace[1729251241] 'agreement among raft nodes before linearized reading'  (duration: 411.804544ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:33:28.199707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:33:27.781679Z","time spent":"417.99282ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-04-07T00:33:28.199736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:33:27.787707Z","time spent":"412.021049ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"info","ts":"2026-04-07T00:36:43.670326Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12991}
{"level":"info","ts":"2026-04-07T00:36:43.753205Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12991,"took":"80.308409ms","hash":808073177,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":30605312,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-04-07T00:36:43.7533Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":808073177,"revision":12991,"compact-revision":10938}
{"level":"info","ts":"2026-04-07T00:40:25.850668Z","caller":"traceutil/trace.go:171","msg":"trace[129086963] transaction","detail":"{read_only:false; response_revision:17157; number_of_response:1; }","duration":"106.439734ms","start":"2026-04-07T00:40:25.744202Z","end":"2026-04-07T00:40:25.850642Z","steps":["trace[129086963] 'process raft request'  (duration: 106.28202ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:41:43.676085Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15373}
{"level":"info","ts":"2026-04-07T00:41:43.784334Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15373,"took":"105.021203ms","hash":1100797535,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":33955840,"current-db-size-in-use":"34 MB"}
{"level":"info","ts":"2026-04-07T00:41:43.784395Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1100797535,"revision":15373,"compact-revision":12991}
{"level":"info","ts":"2026-04-07T00:44:46.60831Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e98569549cc430c","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-04-07T00:44:46.624043Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002}
{"level":"info","ts":"2026-04-07T00:44:46.624235Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}
{"level":"warn","ts":"2026-04-07T00:46:07.503647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.495845ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4831409558508206390 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:19807 > 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:18"}
{"level":"info","ts":"2026-04-07T00:46:07.503876Z","caller":"traceutil/trace.go:171","msg":"trace[399105044] transaction","detail":"{read_only:false; response_revision:19818; number_of_response:1; }","duration":"209.624348ms","start":"2026-04-07T00:46:07.294216Z","end":"2026-04-07T00:46:07.50384Z","steps":["trace[399105044] 'process raft request'  (duration: 76.817136ms)","trace[399105044] 'compare'  (duration: 132.350562ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:46:07.740307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.75755ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:46:07.740505Z","caller":"traceutil/trace.go:171","msg":"trace[1384999089] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:19819; }","duration":"113.056126ms","start":"2026-04-07T00:46:07.627415Z","end":"2026-04-07T00:46:07.740472Z","steps":["trace[1384999089] 'range keys from in-memory index tree'  (duration: 112.724109ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:46:07.7405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.982237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"warn","ts":"2026-04-07T00:46:07.740548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.002721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"}
{"level":"info","ts":"2026-04-07T00:46:07.740606Z","caller":"traceutil/trace.go:171","msg":"trace[1551609023] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:19819; }","duration":"134.171852ms","start":"2026-04-07T00:46:07.606414Z","end":"2026-04-07T00:46:07.740586Z","steps":["trace[1551609023] 'count revisions from in-memory index tree'  (duration: 133.892155ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:46:07.740658Z","caller":"traceutil/trace.go:171","msg":"trace[1939754146] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19819; }","duration":"136.175085ms","start":"2026-04-07T00:46:07.604458Z","end":"2026-04-07T00:46:07.740633Z","steps":["trace[1939754146] 'range keys from in-memory index tree'  (duration: 135.715744ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:46:08.230932Z","caller":"traceutil/trace.go:171","msg":"trace[148189944] linearizableReadLoop","detail":"{readStateIndex:20489; appliedIndex:20488; }","duration":"162.912042ms","start":"2026-04-07T00:46:08.067985Z","end":"2026-04-07T00:46:08.230897Z","steps":["trace[148189944] 'read index received'  (duration: 162.702556ms)","trace[148189944] 'applied index is now lower than readState.Index'  (duration: 207.816µs)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:46:08.231015Z","caller":"traceutil/trace.go:171","msg":"trace[718234937] transaction","detail":"{read_only:false; response_revision:19820; number_of_response:1; }","duration":"317.653496ms","start":"2026-04-07T00:46:07.913338Z","end":"2026-04-07T00:46:08.230992Z","steps":["trace[718234937] 'process raft request'  (duration: 317.374979ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:46:08.231161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:46:07.913304Z","time spent":"317.761588ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:19810 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-04-07T00:46:08.231299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.290129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"}
{"level":"warn","ts":"2026-04-07T00:46:08.231345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.718088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:46:08.231455Z","caller":"traceutil/trace.go:171","msg":"trace[1625969647] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:19820; }","duration":"131.911322ms","start":"2026-04-07T00:46:08.099517Z","end":"2026-04-07T00:46:08.231429Z","steps":["trace[1625969647] 'agreement among raft nodes before linearized reading'  (duration: 131.681187ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:46:08.231483Z","caller":"traceutil/trace.go:171","msg":"trace[962918069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19820; }","duration":"163.470223ms","start":"2026-04-07T00:46:08.067921Z","end":"2026-04-07T00:46:08.231391Z","steps":["trace[962918069] 'agreement among raft nodes before linearized reading'  (duration: 163.097005ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:46:43.682627Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18004}
{"level":"info","ts":"2026-04-07T00:46:43.809348Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18004,"took":"121.14747ms","hash":2354524060,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":30515200,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-04-07T00:46:43.809431Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2354524060,"revision":18004,"compact-revision":15373}
{"level":"warn","ts":"2026-04-07T00:49:42.528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.90476ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4831409558508213137 > lease_revoke:<id:430c9d65434f0c50>","response":"size:28"}
{"level":"info","ts":"2026-04-07T00:49:42.528315Z","caller":"traceutil/trace.go:171","msg":"trace[1423307028] linearizableReadLoop","detail":"{readStateIndex:22830; appliedIndex:22829; }","duration":"366.133002ms","start":"2026-04-07T00:49:42.162164Z","end":"2026-04-07T00:49:42.528297Z","steps":["trace[1423307028] 'read index received'  (duration: 33.141µs)","trace[1423307028] 'applied index is now lower than readState.Index'  (duration: 366.098831ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:49:42.528393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.233253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:49:42.528451Z","caller":"traceutil/trace.go:171","msg":"trace[1315798095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22089; }","duration":"366.331256ms","start":"2026-04-07T00:49:42.162103Z","end":"2026-04-07T00:49:42.528434Z","steps":["trace[1315798095] 'agreement among raft nodes before linearized reading'  (duration: 366.241804ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:49:42.52849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:49:42.162081Z","time spent":"366.400957ms","remote":"127.0.0.1:41018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-04-07T00:49:42.528657Z","caller":"traceutil/trace.go:171","msg":"trace[1297992294] transaction","detail":"{read_only:false; response_revision:22090; number_of_response:1; }","duration":"340.265535ms","start":"2026-04-07T00:49:42.18835Z","end":"2026-04-07T00:49:42.528615Z","steps":["trace[1297992294] 'process raft request'  (duration: 339.845826ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:49:42.528848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:49:42.188332Z","time spent":"340.414908ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:22042 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:453 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-04-07T00:49:42.548624Z","caller":"traceutil/trace.go:171","msg":"trace[508603582] transaction","detail":"{read_only:false; response_revision:22091; number_of_response:1; }","duration":"325.254094ms","start":"2026-04-07T00:49:42.223357Z","end":"2026-04-07T00:49:42.548612Z","steps":["trace[508603582] 'process raft request'  (duration: 305.267005ms)","trace[508603582] 'compare'  (duration: 19.868407ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:49:42.548697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:49:42.223327Z","time spent":"325.333846ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:22045 > success:<request_put:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" value_size:451 >> failure:<request_range:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" > >"}
{"level":"info","ts":"2026-04-07T00:49:42.548821Z","caller":"traceutil/trace.go:171","msg":"trace[1646031825] transaction","detail":"{read_only:false; response_revision:22092; number_of_response:1; }","duration":"259.582216ms","start":"2026-04-07T00:49:42.289196Z","end":"2026-04-07T00:49:42.548778Z","steps":["trace[1646031825] 'process raft request'  (duration: 259.384411ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:49:42.548867Z","caller":"traceutil/trace.go:171","msg":"trace[341065325] transaction","detail":"{read_only:false; response_revision:22095; number_of_response:1; }","duration":"139.679545ms","start":"2026-04-07T00:49:42.409169Z","end":"2026-04-07T00:49:42.548848Z","steps":["trace[341065325] 'process raft request'  (duration: 139.585623ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:49:42.548867Z","caller":"traceutil/trace.go:171","msg":"trace[1747364404] transaction","detail":"{read_only:false; response_revision:22094; number_of_response:1; }","duration":"144.332945ms","start":"2026-04-07T00:49:42.40449Z","end":"2026-04-07T00:49:42.548823Z","steps":["trace[1747364404] 'process raft request'  (duration: 144.226742ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:49:42.548934Z","caller":"traceutil/trace.go:171","msg":"trace[1854986778] transaction","detail":"{read_only:false; response_revision:22093; number_of_response:1; }","duration":"150.386344ms","start":"2026-04-07T00:49:42.398466Z","end":"2026-04-07T00:49:42.548852Z","steps":["trace[1854986778] 'process raft request'  (duration: 150.19071ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:49:42.549043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.604554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"}
{"level":"info","ts":"2026-04-07T00:49:42.549112Z","caller":"traceutil/trace.go:171","msg":"trace[1328967501] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22095; }","duration":"257.714346ms","start":"2026-04-07T00:49:42.291382Z","end":"2026-04-07T00:49:42.549097Z","steps":["trace[1328967501] 'agreement among raft nodes before linearized reading'  (duration: 257.477251ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:49:42.549095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.367713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"}
{"level":"info","ts":"2026-04-07T00:49:42.549192Z","caller":"traceutil/trace.go:171","msg":"trace[1116270159] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:22095; }","duration":"212.506046ms","start":"2026-04-07T00:49:42.336667Z","end":"2026-04-07T00:49:42.549173Z","steps":["trace[1116270159] 'agreement among raft nodes before linearized reading'  (duration: 212.262431ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:49:42.549192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.18597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:626"}
{"level":"info","ts":"2026-04-07T00:49:42.549259Z","caller":"traceutil/trace.go:171","msg":"trace[2002811222] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:22095; }","duration":"178.293882ms","start":"2026-04-07T00:49:42.370946Z","end":"2026-04-07T00:49:42.54924Z","steps":["trace[2002811222] 'agreement among raft nodes before linearized reading'  (duration: 177.981006ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:51:43.689831Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20258}
{"level":"info","ts":"2026-04-07T00:51:43.805642Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20258,"took":"110.233682ms","hash":2083619501,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":42053632,"current-db-size-in-use":"42 MB"}
{"level":"info","ts":"2026-04-07T00:51:43.805723Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2083619501,"revision":20258,"compact-revision":18004}
{"level":"info","ts":"2026-04-07T00:52:56.47963Z","caller":"traceutil/trace.go:171","msg":"trace[491909490] transaction","detail":"{read_only:false; response_revision:24103; number_of_response:1; }","duration":"122.004384ms","start":"2026-04-07T00:52:56.357593Z","end":"2026-04-07T00:52:56.479598Z","steps":["trace[491909490] 'process raft request'  (duration: 120.338859ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:52:56.491702Z","caller":"traceutil/trace.go:171","msg":"trace[1184516689] transaction","detail":"{read_only:false; response_revision:24105; number_of_response:1; }","duration":"131.110029ms","start":"2026-04-07T00:52:56.360559Z","end":"2026-04-07T00:52:56.491669Z","steps":["trace[1184516689] 'process raft request'  (duration: 131.005767ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:52:56.491699Z","caller":"traceutil/trace.go:171","msg":"trace[1799977356] transaction","detail":"{read_only:false; response_revision:24104; number_of_response:1; }","duration":"131.753523ms","start":"2026-04-07T00:52:56.359899Z","end":"2026-04-07T00:52:56.491652Z","steps":["trace[1799977356] 'process raft request'  (duration: 131.549158ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:00.599086Z","caller":"traceutil/trace.go:171","msg":"trace[911586334] linearizableReadLoop","detail":"{readStateIndex:24926; appliedIndex:24925; }","duration":"105.45388ms","start":"2026-04-07T00:53:00.493605Z","end":"2026-04-07T00:53:00.599059Z","steps":["trace[911586334] 'read index received'  (duration: 105.423159ms)","trace[911586334] 'applied index is now lower than readState.Index'  (duration: 29.381µs)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:00.599128Z","caller":"traceutil/trace.go:171","msg":"trace[354803965] transaction","detail":"{read_only:false; response_revision:24132; number_of_response:1; }","duration":"234.685269ms","start":"2026-04-07T00:53:00.364414Z","end":"2026-04-07T00:53:00.599099Z","steps":["trace[354803965] 'process raft request'  (duration: 234.448774ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:00.599333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.717675ms","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-04-07T00:53:00.599381Z","caller":"traceutil/trace.go:171","msg":"trace[83101039] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24132; }","duration":"105.824427ms","start":"2026-04-07T00:53:00.493541Z","end":"2026-04-07T00:53:00.599365Z","steps":["trace[83101039] 'agreement among raft nodes before linearized reading'  (duration: 105.652404ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:00.664593Z","caller":"traceutil/trace.go:171","msg":"trace[59055876] transaction","detail":"{read_only:false; response_revision:24133; number_of_response:1; }","duration":"155.404219ms","start":"2026-04-07T00:53:00.509164Z","end":"2026-04-07T00:53:00.664568Z","steps":["trace[59055876] 'process raft request'  (duration: 153.867997ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:00.664988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.882357ms","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-04-07T00:53:00.665069Z","caller":"traceutil/trace.go:171","msg":"trace[884638258] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24134; }","duration":"146.974059ms","start":"2026-04-07T00:53:00.518076Z","end":"2026-04-07T00:53:00.66505Z","steps":["trace[884638258] 'agreement among raft nodes before linearized reading'  (duration: 146.757384ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:00.665038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.500571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"}
{"level":"warn","ts":"2026-04-07T00:53:00.665166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.192635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"}
{"level":"info","ts":"2026-04-07T00:53:00.665238Z","caller":"traceutil/trace.go:171","msg":"trace[1794164731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24134; }","duration":"155.786668ms","start":"2026-04-07T00:53:00.509428Z","end":"2026-04-07T00:53:00.665214Z","steps":["trace[1794164731] 'agreement among raft nodes before linearized reading'  (duration: 155.43535ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:00.665133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.503011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"}
{"level":"info","ts":"2026-04-07T00:53:00.665495Z","caller":"traceutil/trace.go:171","msg":"trace[751069054] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24134; }","duration":"154.878619ms","start":"2026-04-07T00:53:00.510589Z","end":"2026-04-07T00:53:00.665468Z","steps":["trace[751069054] 'agreement among raft nodes before linearized reading'  (duration: 154.321517ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:00.665298Z","caller":"traceutil/trace.go:171","msg":"trace[823078874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24134; }","duration":"155.355499ms","start":"2026-04-07T00:53:00.509915Z","end":"2026-04-07T00:53:00.665271Z","steps":["trace[823078874] 'agreement among raft nodes before linearized reading'  (duration: 155.016241ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:00.933296Z","caller":"traceutil/trace.go:171","msg":"trace[523509123] transaction","detail":"{read_only:false; response_revision:24136; number_of_response:1; }","duration":"262.153267ms","start":"2026-04-07T00:53:00.671114Z","end":"2026-04-07T00:53:00.933267Z","steps":["trace[523509123] 'process raft request'  (duration: 261.952522ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:00.933578Z","caller":"traceutil/trace.go:171","msg":"trace[1610959540] transaction","detail":"{read_only:false; response_revision:24137; number_of_response:1; }","duration":"262.269819ms","start":"2026-04-07T00:53:00.671291Z","end":"2026-04-07T00:53:00.933561Z","steps":["trace[1610959540] 'process raft request'  (duration: 261.930162ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:00.933862Z","caller":"traceutil/trace.go:171","msg":"trace[887290825] transaction","detail":"{read_only:false; response_revision:24138; number_of_response:1; }","duration":"261.701677ms","start":"2026-04-07T00:53:00.672148Z","end":"2026-04-07T00:53:00.93385Z","steps":["trace[887290825] 'process raft request'  (duration: 261.34921ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:00.933978Z","caller":"traceutil/trace.go:171","msg":"trace[1109872133] linearizableReadLoop","detail":"{readStateIndex:24933; appliedIndex:24928; }","duration":"245.469359ms","start":"2026-04-07T00:53:00.688498Z","end":"2026-04-07T00:53:00.933968Z","steps":["trace[1109872133] 'read index received'  (duration: 67.016516ms)","trace[1109872133] 'applied index is now lower than readState.Index'  (duration: 178.451973ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:53:00.934115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.599483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:53:00.934141Z","caller":"traceutil/trace.go:171","msg":"trace[354870296] transaction","detail":"{read_only:false; response_revision:24139; number_of_response:1; }","duration":"261.439542ms","start":"2026-04-07T00:53:00.672696Z","end":"2026-04-07T00:53:00.934135Z","steps":["trace[354870296] 'process raft request'  (duration: 261.111044ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:00.934156Z","caller":"traceutil/trace.go:171","msg":"trace[675394408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24139; }","duration":"245.691024ms","start":"2026-04-07T00:53:00.688455Z","end":"2026-04-07T00:53:00.934146Z","steps":["trace[675394408] 'agreement among raft nodes before linearized reading'  (duration: 245.592951ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:01.670901Z","caller":"traceutil/trace.go:171","msg":"trace[2092906525] transaction","detail":"{read_only:false; response_revision:24143; number_of_response:1; }","duration":"251.57655ms","start":"2026-04-07T00:53:01.419301Z","end":"2026-04-07T00:53:01.670877Z","steps":["trace[2092906525] 'process raft request'  (duration: 251.415846ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:05.630011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.308336ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4831409558508218083 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:24156 > 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\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-07T00:53:05.630215Z","caller":"traceutil/trace.go:171","msg":"trace[361081105] transaction","detail":"{read_only:false; response_revision:24173; number_of_response:1; }","duration":"293.720394ms","start":"2026-04-07T00:53:05.336481Z","end":"2026-04-07T00:53:05.630202Z","steps":["trace[361081105] 'process raft request'  (duration: 293.689353ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:05.630332Z","caller":"traceutil/trace.go:171","msg":"trace[973737917] transaction","detail":"{read_only:false; response_revision:24172; number_of_response:1; }","duration":"297.7567ms","start":"2026-04-07T00:53:05.332539Z","end":"2026-04-07T00:53:05.630295Z","steps":["trace[973737917] 'process raft request'  (duration: 297.568946ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:05.630243Z","caller":"traceutil/trace.go:171","msg":"trace[1109207009] transaction","detail":"{read_only:false; response_revision:24171; number_of_response:1; }","duration":"297.894783ms","start":"2026-04-07T00:53:05.332304Z","end":"2026-04-07T00:53:05.630199Z","steps":["trace[1109207009] 'process raft request'  (duration: 116.308281ms)","trace[1109207009] 'compare'  (duration: 181.220144ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:06.033549Z","caller":"traceutil/trace.go:171","msg":"trace[1302899721] transaction","detail":"{read_only:false; response_revision:24174; number_of_response:1; }","duration":"343.676094ms","start":"2026-04-07T00:53:05.689853Z","end":"2026-04-07T00:53:06.03353Z","steps":["trace[1302899721] 'process raft request'  (duration: 343.542831ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:06.033668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:05.689834Z","time spent":"343.768286ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/capi-system/controller-leader-election-capi\" mod_revision:24159 > success:<request_put:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" > >"}
{"level":"info","ts":"2026-04-07T00:53:06.043895Z","caller":"traceutil/trace.go:171","msg":"trace[1740430130] transaction","detail":"{read_only:false; response_revision:24175; number_of_response:1; }","duration":"270.051057ms","start":"2026-04-07T00:53:05.773813Z","end":"2026-04-07T00:53:06.043864Z","steps":["trace[1740430130] 'process raft request'  (duration: 269.921653ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:06.939431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.700326ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4831409558508218089 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:24162 > success:<request_put:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-07T00:53:06.939598Z","caller":"traceutil/trace.go:171","msg":"trace[158215880] linearizableReadLoop","detail":"{readStateIndex:24972; appliedIndex:24971; }","duration":"221.028516ms","start":"2026-04-07T00:53:06.718545Z","end":"2026-04-07T00:53:06.939574Z","steps":["trace[158215880] 'read index received'  (duration: 30.047494ms)","trace[158215880] 'applied index is now lower than readState.Index'  (duration: 190.978812ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:53:06.939857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.305721ms","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-04-07T00:53:06.939926Z","caller":"traceutil/trace.go:171","msg":"trace[1962007278] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24177; }","duration":"221.403514ms","start":"2026-04-07T00:53:06.718507Z","end":"2026-04-07T00:53:06.939911Z","steps":["trace[1962007278] 'agreement among raft nodes before linearized reading'  (duration: 221.195359ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:06.939743Z","caller":"traceutil/trace.go:171","msg":"trace[573989778] transaction","detail":"{read_only:false; response_revision:24177; number_of_response:1; }","duration":"221.339103ms","start":"2026-04-07T00:53:06.71832Z","end":"2026-04-07T00:53:06.939659Z","steps":["trace[573989778] 'process raft request'  (duration: 30.275869ms)","trace[573989778] 'compare'  (duration: 190.504122ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:53:06.940082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.944316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:140"}
{"level":"info","ts":"2026-04-07T00:53:06.940166Z","caller":"traceutil/trace.go:171","msg":"trace[1630787585] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:24177; }","duration":"194.070458ms","start":"2026-04-07T00:53:06.746066Z","end":"2026-04-07T00:53:06.940137Z","steps":["trace[1630787585] 'agreement among raft nodes before linearized reading'  (duration: 193.812502ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:06.940147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.50626ms","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-04-07T00:53:06.940272Z","caller":"traceutil/trace.go:171","msg":"trace[1110912346] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24177; }","duration":"147.696155ms","start":"2026-04-07T00:53:06.79255Z","end":"2026-04-07T00:53:06.940246Z","steps":["trace[1110912346] 'agreement among raft nodes before linearized reading'  (duration: 147.328416ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:07.264333Z","caller":"traceutil/trace.go:171","msg":"trace[1024990055] linearizableReadLoop","detail":"{readStateIndex:24975; appliedIndex:24972; }","duration":"302.626764ms","start":"2026-04-07T00:53:06.961677Z","end":"2026-04-07T00:53:07.264303Z","steps":["trace[1024990055] 'read index received'  (duration: 646.494µs)","trace[1024990055] 'applied index is now lower than readState.Index'  (duration: 301.9791ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:07.264493Z","caller":"traceutil/trace.go:171","msg":"trace[1774748891] transaction","detail":"{read_only:false; response_revision:24178; number_of_response:1; }","duration":"320.214411ms","start":"2026-04-07T00:53:06.944258Z","end":"2026-04-07T00:53:07.264473Z","steps":["trace[1774748891] 'process raft request'  (duration: 283.76255ms)","trace[1774748891] 'compare'  (duration: 35.990371ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:53:07.264627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:06.944239Z","time spent":"320.301682ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:24163 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-04-07T00:53:07.264737Z","caller":"traceutil/trace.go:171","msg":"trace[2056577371] transaction","detail":"{read_only:false; response_revision:24179; number_of_response:1; }","duration":"319.965805ms","start":"2026-04-07T00:53:06.944712Z","end":"2026-04-07T00:53:07.264678Z","steps":["trace[2056577371] 'process raft request'  (duration: 319.524086ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:07.264836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.137135ms","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-04-07T00:53:07.264968Z","caller":"traceutil/trace.go:171","msg":"trace[1605498394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24179; }","duration":"303.322429ms","start":"2026-04-07T00:53:06.961622Z","end":"2026-04-07T00:53:07.264945Z","steps":["trace[1605498394] 'agreement among raft nodes before linearized reading'  (duration: 303.029333ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:07.264989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:06.944687Z","time spent":"320.16637ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:24164 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-04-07T00:53:07.265013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:06.961603Z","time spent":"303.3999ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-04-07T00:53:07.282486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.541994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"}
{"level":"info","ts":"2026-04-07T00:53:07.282558Z","caller":"traceutil/trace.go:171","msg":"trace[214769270] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24180; }","duration":"317.659287ms","start":"2026-04-07T00:53:06.96488Z","end":"2026-04-07T00:53:07.282539Z","steps":["trace[214769270] 'agreement among raft nodes before linearized reading'  (duration: 317.470893ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:07.282586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.606208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:53:07.282647Z","caller":"traceutil/trace.go:171","msg":"trace[767739665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24180; }","duration":"123.69218ms","start":"2026-04-07T00:53:07.158939Z","end":"2026-04-07T00:53:07.282631Z","steps":["trace[767739665] 'agreement among raft nodes before linearized reading'  (duration: 123.594188ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:07.282687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.669295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"}
{"level":"info","ts":"2026-04-07T00:53:07.282714Z","caller":"traceutil/trace.go:171","msg":"trace[58492815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24180; }","duration":"316.762177ms","start":"2026-04-07T00:53:06.965943Z","end":"2026-04-07T00:53:07.282706Z","steps":["trace[58492815] 'agreement among raft nodes before linearized reading'  (duration: 316.679145ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:07.282745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:06.965921Z","time spent":"316.818218ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-04-07T00:53:07.282754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.856988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"}
{"level":"info","ts":"2026-04-07T00:53:07.282769Z","caller":"traceutil/trace.go:171","msg":"trace[1163147351] transaction","detail":"{read_only:false; response_revision:24180; number_of_response:1; }","duration":"319.077537ms","start":"2026-04-07T00:53:06.963672Z","end":"2026-04-07T00:53:07.282749Z","steps":["trace[1163147351] 'process raft request'  (duration: 318.539926ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:07.282827Z","caller":"traceutil/trace.go:171","msg":"trace[1335436707] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24180; }","duration":"315.95651ms","start":"2026-04-07T00:53:06.966854Z","end":"2026-04-07T00:53:07.282811Z","steps":["trace[1335436707] 'agreement among raft nodes before linearized reading'  (duration: 315.759466ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:07.282599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:06.964867Z","time spent":"317.723039ms","remote":"127.0.0.1:41308","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-04-07T00:53:07.282879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:06.963652Z","time spent":"319.173449ms","remote":"127.0.0.1:41042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/masterleases/199.204.45.216\" mod_revision:24110 > success:<request_put:<key:\"/registry/masterleases/199.204.45.216\" value_size:68 lease:4831409558508218092 >> failure:<request_range:<key:\"/registry/masterleases/199.204.45.216\" > >"}
{"level":"warn","ts":"2026-04-07T00:53:07.282881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:06.96684Z","time spent":"316.029232ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-04-07T00:53:07.282482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.775684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-04-07T00:53:07.283053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.37115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"}
{"level":"info","ts":"2026-04-07T00:53:07.283094Z","caller":"traceutil/trace.go:171","msg":"trace[1951469147] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:24180; }","duration":"167.438298ms","start":"2026-04-07T00:53:07.115635Z","end":"2026-04-07T00:53:07.283073Z","steps":["trace[1951469147] 'agreement among raft nodes before linearized reading'  (duration: 166.738573ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:07.28313Z","caller":"traceutil/trace.go:171","msg":"trace[4145397] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24180; }","duration":"233.529373ms","start":"2026-04-07T00:53:07.049579Z","end":"2026-04-07T00:53:07.283108Z","steps":["trace[4145397] 'agreement among raft nodes before linearized reading'  (duration: 233.088353ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:07.409517Z","caller":"traceutil/trace.go:171","msg":"trace[1535693449] transaction","detail":"{read_only:false; response_revision:24181; number_of_response:1; }","duration":"140.602522ms","start":"2026-04-07T00:53:07.268886Z","end":"2026-04-07T00:53:07.409488Z","steps":["trace[1535693449] 'process raft request'  (duration: 139.748754ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:07.409629Z","caller":"traceutil/trace.go:171","msg":"trace[1243871783] linearizableReadLoop","detail":"{readStateIndex:24977; appliedIndex:24976; }","duration":"125.674973ms","start":"2026-04-07T00:53:07.2839Z","end":"2026-04-07T00:53:07.409575Z","steps":["trace[1243871783] 'read index received'  (duration: 124.830706ms)","trace[1243871783] 'applied index is now lower than readState.Index'  (duration: 842.457µs)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:07.409638Z","caller":"traceutil/trace.go:171","msg":"trace[1136294168] transaction","detail":"{read_only:false; response_revision:24182; number_of_response:1; }","duration":"122.170238ms","start":"2026-04-07T00:53:07.287436Z","end":"2026-04-07T00:53:07.409606Z","steps":["trace[1136294168] 'process raft request'  (duration: 121.973594ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:07.410019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.032931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:53:07.410141Z","caller":"traceutil/trace.go:171","msg":"trace[1094333540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24183; }","duration":"126.242605ms","start":"2026-04-07T00:53:07.283851Z","end":"2026-04-07T00:53:07.410093Z","steps":["trace[1094333540] 'agreement among raft nodes before linearized reading'  (duration: 125.938409ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:07.410149Z","caller":"traceutil/trace.go:171","msg":"trace[1467504411] transaction","detail":"{read_only:false; response_revision:24183; number_of_response:1; }","duration":"121.420171ms","start":"2026-04-07T00:53:07.28871Z","end":"2026-04-07T00:53:07.41013Z","steps":["trace[1467504411] 'process raft request'  (duration: 120.834159ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:07.410913Z","caller":"traceutil/trace.go:171","msg":"trace[1986444092] transaction","detail":"{read_only:false; response_revision:24184; number_of_response:1; }","duration":"122.050916ms","start":"2026-04-07T00:53:07.288829Z","end":"2026-04-07T00:53:07.41088Z","steps":["trace[1986444092] 'process raft request'  (duration: 120.981172ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:07.411171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.095822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"}
{"level":"info","ts":"2026-04-07T00:53:07.411279Z","caller":"traceutil/trace.go:171","msg":"trace[740947489] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24184; }","duration":"126.258095ms","start":"2026-04-07T00:53:07.284994Z","end":"2026-04-07T00:53:07.411252Z","steps":["trace[740947489] 'agreement among raft nodes before linearized reading'  (duration: 125.739173ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:07.411334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.291524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"}
{"level":"info","ts":"2026-04-07T00:53:07.411539Z","caller":"traceutil/trace.go:171","msg":"trace[697786252] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24184; }","duration":"124.560089ms","start":"2026-04-07T00:53:07.286955Z","end":"2026-04-07T00:53:07.411515Z","steps":["trace[697786252] 'agreement among raft nodes before linearized reading'  (duration: 123.992417ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:07.834025Z","caller":"traceutil/trace.go:171","msg":"trace[1959192879] linearizableReadLoop","detail":"{readStateIndex:24983; appliedIndex:24981; }","duration":"124.188031ms","start":"2026-04-07T00:53:07.709771Z","end":"2026-04-07T00:53:07.833959Z","steps":["trace[1959192879] 'read index received'  (duration: 43.960502ms)","trace[1959192879] 'applied index is now lower than readState.Index'  (duration: 80.226419ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:07.834181Z","caller":"traceutil/trace.go:171","msg":"trace[991465791] transaction","detail":"{read_only:false; response_revision:24187; number_of_response:1; }","duration":"197.425121ms","start":"2026-04-07T00:53:07.636741Z","end":"2026-04-07T00:53:07.834166Z","steps":["trace[991465791] 'process raft request'  (duration: 197.138055ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:07.83417Z","caller":"traceutil/trace.go:171","msg":"trace[1630456349] transaction","detail":"{read_only:false; response_revision:24186; number_of_response:1; }","duration":"198.137565ms","start":"2026-04-07T00:53:07.636003Z","end":"2026-04-07T00:53:07.834141Z","steps":["trace[1630456349] 'process raft request'  (duration: 117.836175ms)","trace[1630456349] 'compare'  (duration: 79.829729ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:53:07.834697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.894677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274398"}
{"level":"info","ts":"2026-04-07T00:53:07.834755Z","caller":"traceutil/trace.go:171","msg":"trace[1815295742] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:24187; }","duration":"125.03238ms","start":"2026-04-07T00:53:07.709708Z","end":"2026-04-07T00:53:07.834741Z","steps":["trace[1815295742] 'agreement among raft nodes before linearized reading'  (duration: 124.58234ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:08.172712Z","caller":"traceutil/trace.go:171","msg":"trace[1613307651] transaction","detail":"{read_only:false; response_revision:24189; number_of_response:1; }","duration":"134.372689ms","start":"2026-04-07T00:53:08.038308Z","end":"2026-04-07T00:53:08.172681Z","steps":["trace[1613307651] 'process raft request'  (duration: 134.170345ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:08.174928Z","caller":"traceutil/trace.go:171","msg":"trace[2000132684] transaction","detail":"{read_only:false; response_revision:24190; number_of_response:1; }","duration":"123.860284ms","start":"2026-04-07T00:53:08.051034Z","end":"2026-04-07T00:53:08.174894Z","steps":["trace[2000132684] 'process raft request'  (duration: 123.599248ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:08.954302Z","caller":"traceutil/trace.go:171","msg":"trace[1652395747] transaction","detail":"{read_only:false; response_revision:24191; number_of_response:1; }","duration":"223.759164ms","start":"2026-04-07T00:53:08.730516Z","end":"2026-04-07T00:53:08.954275Z","steps":["trace[1652395747] 'process raft request'  (duration: 223.5759ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:09.241578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.339325ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4831409558508218126 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:24177 > success:<request_put:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-07T00:53:09.241648Z","caller":"traceutil/trace.go:171","msg":"trace[1686054129] linearizableReadLoop","detail":"{readStateIndex:24988; appliedIndex:24987; }","duration":"269.090046ms","start":"2026-04-07T00:53:08.972547Z","end":"2026-04-07T00:53:09.241637Z","steps":["trace[1686054129] 'read index received'  (duration: 80.558097ms)","trace[1686054129] 'applied index is now lower than readState.Index'  (duration: 188.531369ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:53:09.241902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.354791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-04-07T00:53:09.241882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.324607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-04-07T00:53:09.24192Z","caller":"traceutil/trace.go:171","msg":"trace[1324402674] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:24192; }","duration":"269.409373ms","start":"2026-04-07T00:53:08.972504Z","end":"2026-04-07T00:53:09.241913Z","steps":["trace[1324402674] 'agreement among raft nodes before linearized reading'  (duration: 269.369472ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:09.241938Z","caller":"traceutil/trace.go:171","msg":"trace[1652678245] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:24192; }","duration":"127.43049ms","start":"2026-04-07T00:53:09.114494Z","end":"2026-04-07T00:53:09.241924Z","steps":["trace[1652678245] 'agreement among raft nodes before linearized reading'  (duration: 127.308947ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:09.241958Z","caller":"traceutil/trace.go:171","msg":"trace[2076740715] transaction","detail":"{read_only:false; response_revision:24192; number_of_response:1; }","duration":"295.92642ms","start":"2026-04-07T00:53:08.946005Z","end":"2026-04-07T00:53:09.241932Z","steps":["trace[2076740715] 'process raft request'  (duration: 107.176926ms)","trace[2076740715] 'compare'  (duration: 188.004218ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:53:09.503141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.402198ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4831409558508218131 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:24179 > 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\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-07T00:53:09.503295Z","caller":"traceutil/trace.go:171","msg":"trace[834871296] linearizableReadLoop","detail":"{readStateIndex:24990; appliedIndex:24988; }","duration":"161.867778ms","start":"2026-04-07T00:53:09.341413Z","end":"2026-04-07T00:53:09.50328Z","steps":["trace[834871296] 'read index received'  (duration: 7.258805ms)","trace[834871296] 'applied index is now lower than readState.Index'  (duration: 154.607653ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:09.503376Z","caller":"traceutil/trace.go:171","msg":"trace[1357675880] transaction","detail":"{read_only:false; response_revision:24193; number_of_response:1; }","duration":"229.966407ms","start":"2026-04-07T00:53:09.2734Z","end":"2026-04-07T00:53:09.503367Z","steps":["trace[1357675880] 'process raft request'  (duration: 75.264173ms)","trace[1357675880] 'compare'  (duration: 154.305056ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:53:09.503489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.052542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-04-07T00:53:09.50357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.560696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:53:09.503598Z","caller":"traceutil/trace.go:171","msg":"trace[1597353634] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:24194; }","duration":"122.608007ms","start":"2026-04-07T00:53:09.38098Z","end":"2026-04-07T00:53:09.503588Z","steps":["trace[1597353634] 'agreement among raft nodes before linearized reading'  (duration: 122.556156ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:09.503607Z","caller":"traceutil/trace.go:171","msg":"trace[1284534880] transaction","detail":"{read_only:false; response_revision:24194; number_of_response:1; }","duration":"229.653771ms","start":"2026-04-07T00:53:09.273942Z","end":"2026-04-07T00:53:09.503595Z","steps":["trace[1284534880] 'process raft request'  (duration: 229.291713ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:09.503562Z","caller":"traceutil/trace.go:171","msg":"trace[1458373022] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:24194; }","duration":"162.156494ms","start":"2026-04-07T00:53:09.341385Z","end":"2026-04-07T00:53:09.503541Z","steps":["trace[1458373022] 'agreement among raft nodes before linearized reading'  (duration: 162.042211ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:09.651317Z","caller":"traceutil/trace.go:171","msg":"trace[790185310] transaction","detail":"{read_only:false; response_revision:24195; number_of_response:1; }","duration":"143.569046ms","start":"2026-04-07T00:53:09.507726Z","end":"2026-04-07T00:53:09.651295Z","steps":["trace[790185310] 'process raft request'  (duration: 138.59331ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:09.978261Z","caller":"traceutil/trace.go:171","msg":"trace[1639403772] transaction","detail":"{read_only:false; response_revision:24201; number_of_response:1; }","duration":"136.866802ms","start":"2026-04-07T00:53:09.841373Z","end":"2026-04-07T00:53:09.97824Z","steps":["trace[1639403772] 'process raft request'  (duration: 136.841272ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:09.97974Z","caller":"traceutil/trace.go:171","msg":"trace[1915332935] transaction","detail":"{read_only:false; response_revision:24198; number_of_response:1; }","duration":"470.47924ms","start":"2026-04-07T00:53:09.509233Z","end":"2026-04-07T00:53:09.979712Z","steps":["trace[1915332935] 'process raft request'  (duration: 468.909316ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:09.979782Z","caller":"traceutil/trace.go:171","msg":"trace[1136080304] linearizableReadLoop","detail":"{readStateIndex:24994; appliedIndex:24991; }","duration":"330.556733ms","start":"2026-04-07T00:53:09.649213Z","end":"2026-04-07T00:53:09.97977Z","steps":["trace[1136080304] 'read index received'  (duration: 289.420441ms)","trace[1136080304] 'applied index is now lower than readState.Index'  (duration: 41.135052ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:09.979848Z","caller":"traceutil/trace.go:171","msg":"trace[169276779] transaction","detail":"{read_only:false; response_revision:24199; number_of_response:1; }","duration":"288.328598ms","start":"2026-04-07T00:53:09.691513Z","end":"2026-04-07T00:53:09.979842Z","steps":["trace[169276779] 'process raft request'  (duration: 286.657652ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:09.979857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:09.509207Z","time spent":"470.587423ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:24182 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-04-07T00:53:09.979914Z","caller":"traceutil/trace.go:171","msg":"trace[988537308] transaction","detail":"{read_only:false; response_revision:24200; number_of_response:1; }","duration":"140.91851ms","start":"2026-04-07T00:53:09.838989Z","end":"2026-04-07T00:53:09.979908Z","steps":["trace[988537308] 'process raft request'  (duration: 139.203282ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:09.980082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.853609ms","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-04-07T00:53:09.980107Z","caller":"traceutil/trace.go:171","msg":"trace[7732239] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24201; }","duration":"330.921161ms","start":"2026-04-07T00:53:09.649179Z","end":"2026-04-07T00:53:09.9801Z","steps":["trace[7732239] 'agreement among raft nodes before linearized reading'  (duration: 330.782398ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:09.980128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:09.649161Z","time spent":"330.961611ms","remote":"127.0.0.1:41128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":379,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "}
{"level":"warn","ts":"2026-04-07T00:53:09.980164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.457871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7533"}
{"level":"info","ts":"2026-04-07T00:53:09.980189Z","caller":"traceutil/trace.go:171","msg":"trace[108764222] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:24201; }","duration":"330.506571ms","start":"2026-04-07T00:53:09.649677Z","end":"2026-04-07T00:53:09.980183Z","steps":["trace[108764222] 'agreement among raft nodes before linearized reading'  (duration: 330.41831ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:09.980175Z","caller":"traceutil/trace.go:171","msg":"trace[577108630] transaction","detail":"{read_only:false; response_revision:24196; number_of_response:1; }","duration":"471.047143ms","start":"2026-04-07T00:53:09.509105Z","end":"2026-04-07T00:53:09.980152Z","steps":["trace[577108630] 'process raft request'  (duration: 429.605775ms)","trace[577108630] 'compare'  (duration: 39.22915ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:53:09.980204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:09.64967Z","time spent":"330.530362ms","remote":"127.0.0.1:36264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7555,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "}
{"level":"info","ts":"2026-04-07T00:53:09.980275Z","caller":"traceutil/trace.go:171","msg":"trace[1391212418] transaction","detail":"{read_only:false; response_revision:24197; number_of_response:1; }","duration":"471.123084ms","start":"2026-04-07T00:53:09.509143Z","end":"2026-04-07T00:53:09.980266Z","steps":["trace[1391212418] 'process raft request'  (duration: 468.969478ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:09.980307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:09.509089Z","time spent":"471.144694ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:24183 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-04-07T00:53:09.980337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:09.509125Z","time spent":"471.172655ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:24181 > 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-04-07T00:53:10.26827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.557164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:53:10.268337Z","caller":"traceutil/trace.go:171","msg":"trace[593144198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24201; }","duration":"106.681817ms","start":"2026-04-07T00:53:10.16164Z","end":"2026-04-07T00:53:10.268322Z","steps":["trace[593144198] 'range keys from in-memory index tree'  (duration: 106.476642ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:10.586669Z","caller":"traceutil/trace.go:171","msg":"trace[998845128] transaction","detail":"{read_only:false; response_revision:24202; number_of_response:1; }","duration":"409.184858ms","start":"2026-04-07T00:53:10.177444Z","end":"2026-04-07T00:53:10.586629Z","steps":["trace[998845128] 'process raft request'  (duration: 408.933112ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:10.58689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:10.177428Z","time spent":"409.349321ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/capi-system/controller-leader-election-capi\" mod_revision:24189 > success:<request_put:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" value_size:453 >> failure:<request_range:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" > >"}
{"level":"info","ts":"2026-04-07T00:53:10.610556Z","caller":"traceutil/trace.go:171","msg":"trace[1131572938] linearizableReadLoop","detail":"{readStateIndex:24999; appliedIndex:24997; }","duration":"341.418025ms","start":"2026-04-07T00:53:10.26911Z","end":"2026-04-07T00:53:10.610528Z","steps":["trace[1131572938] 'read index received'  (duration: 317.217946ms)","trace[1131572938] 'applied index is now lower than readState.Index'  (duration: 24.198489ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:10.61061Z","caller":"traceutil/trace.go:171","msg":"trace[1350012520] transaction","detail":"{read_only:false; response_revision:24203; number_of_response:1; }","duration":"430.595766ms","start":"2026-04-07T00:53:10.179985Z","end":"2026-04-07T00:53:10.610581Z","steps":["trace[1350012520] 'process raft request'  (duration: 430.373412ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:10.61074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.601799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:53:10.610785Z","caller":"traceutil/trace.go:171","msg":"trace[1075582479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24203; }","duration":"341.692341ms","start":"2026-04-07T00:53:10.26908Z","end":"2026-04-07T00:53:10.610772Z","steps":["trace[1075582479] 'agreement among raft nodes before linearized reading'  (duration: 341.592269ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:10.610782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:10.179971Z","time spent":"430.704328ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:24190 > 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-04-07T00:53:10.610788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.955369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:53:10.611041Z","caller":"traceutil/trace.go:171","msg":"trace[456922243] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:24203; }","duration":"140.257445ms","start":"2026-04-07T00:53:10.470766Z","end":"2026-04-07T00:53:10.611024Z","steps":["trace[456922243] 'agreement among raft nodes before linearized reading'  (duration: 139.954868ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:10.610827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:10.269066Z","time spent":"341.751662ms","remote":"127.0.0.1:41020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-04-07T00:53:11.157253Z","caller":"traceutil/trace.go:171","msg":"trace[170603192] transaction","detail":"{read_only:false; response_revision:24205; number_of_response:1; }","duration":"196.012889ms","start":"2026-04-07T00:53:10.961209Z","end":"2026-04-07T00:53:11.157222Z","steps":["trace[170603192] 'process raft request'  (duration: 195.760154ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:11.452476Z","caller":"traceutil/trace.go:171","msg":"trace[2121812559] linearizableReadLoop","detail":"{readStateIndex:25002; appliedIndex:25001; }","duration":"103.215281ms","start":"2026-04-07T00:53:11.349228Z","end":"2026-04-07T00:53:11.452443Z","steps":["trace[2121812559] 'read index received'  (duration: 54.727773ms)","trace[2121812559] 'applied index is now lower than readState.Index'  (duration: 48.486018ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:11.452559Z","caller":"traceutil/trace.go:171","msg":"trace[1217190615] transaction","detail":"{read_only:false; response_revision:24206; number_of_response:1; }","duration":"204.805118ms","start":"2026-04-07T00:53:11.247723Z","end":"2026-04-07T00:53:11.452528Z","steps":["trace[1217190615] 'process raft request'  (duration: 156.293779ms)","trace[1217190615] 'compare'  (duration: 47.893016ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:53:11.452629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.368465ms","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-04-07T00:53:11.452677Z","caller":"traceutil/trace.go:171","msg":"trace[476899267] 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:24206; }","duration":"103.494197ms","start":"2026-04-07T00:53:11.349171Z","end":"2026-04-07T00:53:11.452665Z","steps":["trace[476899267] 'agreement among raft nodes before linearized reading'  (duration: 103.380245ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:11.887976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.329168ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4831409558508218163 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:24193 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-07T00:53:11.888838Z","caller":"traceutil/trace.go:171","msg":"trace[1001003469] linearizableReadLoop","detail":"{readStateIndex:25004; appliedIndex:25003; }","duration":"232.85101ms","start":"2026-04-07T00:53:11.655958Z","end":"2026-04-07T00:53:11.888809Z","steps":["trace[1001003469] 'read index received'  (duration: 80.592µs)","trace[1001003469] 'applied index is now lower than readState.Index'  (duration: 232.767817ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:11.888881Z","caller":"traceutil/trace.go:171","msg":"trace[1507601143] transaction","detail":"{read_only:false; response_revision:24208; number_of_response:1; }","duration":"377.145982ms","start":"2026-04-07T00:53:11.511668Z","end":"2026-04-07T00:53:11.888814Z","steps":["trace[1507601143] 'process raft request'  (duration: 45.840493ms)","trace[1507601143] 'compare'  (duration: 330.180765ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:53:11.889147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:11.511646Z","time spent":"377.371976ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:24193 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-04-07T00:53:11.889132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.141785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"}
{"level":"warn","ts":"2026-04-07T00:53:11.889187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.245586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-04-07T00:53:11.889239Z","caller":"traceutil/trace.go:171","msg":"trace[1092462218] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24208; }","duration":"233.269038ms","start":"2026-04-07T00:53:11.655944Z","end":"2026-04-07T00:53:11.889213Z","steps":["trace[1092462218] 'agreement among raft nodes before linearized reading'  (duration: 232.949851ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:11.889254Z","caller":"traceutil/trace.go:171","msg":"trace[1451310703] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:24208; }","duration":"190.346858ms","start":"2026-04-07T00:53:11.698888Z","end":"2026-04-07T00:53:11.889235Z","steps":["trace[1451310703] 'agreement among raft nodes before linearized reading'  (duration: 190.210676ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:12.127395Z","caller":"traceutil/trace.go:171","msg":"trace[2086509331] linearizableReadLoop","detail":"{readStateIndex:25008; appliedIndex:25004; }","duration":"141.166824ms","start":"2026-04-07T00:53:11.986206Z","end":"2026-04-07T00:53:12.127373Z","steps":["trace[2086509331] 'read index received'  (duration: 59.179998ms)","trace[2086509331] 'applied index is now lower than readState.Index'  (duration: 81.985836ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:12.127414Z","caller":"traceutil/trace.go:171","msg":"trace[691715565] transaction","detail":"{read_only:false; response_revision:24210; number_of_response:1; }","duration":"142.721248ms","start":"2026-04-07T00:53:11.984661Z","end":"2026-04-07T00:53:12.127382Z","steps":["trace[691715565] 'process raft request'  (duration: 142.598125ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:12.127517Z","caller":"traceutil/trace.go:171","msg":"trace[533528833] transaction","detail":"{read_only:false; response_revision:24211; number_of_response:1; }","duration":"141.831578ms","start":"2026-04-07T00:53:11.985675Z","end":"2026-04-07T00:53:12.127507Z","steps":["trace[533528833] 'process raft request'  (duration: 141.631904ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:12.127636Z","caller":"traceutil/trace.go:171","msg":"trace[8535085] transaction","detail":"{read_only:false; response_revision:24212; number_of_response:1; }","duration":"141.245226ms","start":"2026-04-07T00:53:11.98638Z","end":"2026-04-07T00:53:12.127625Z","steps":["trace[8535085] 'process raft request'  (duration: 140.9611ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:12.127768Z","caller":"traceutil/trace.go:171","msg":"trace[1731149380] transaction","detail":"{read_only:false; response_revision:24209; number_of_response:1; }","duration":"232.45448ms","start":"2026-04-07T00:53:11.895289Z","end":"2026-04-07T00:53:12.127743Z","steps":["trace[1731149380] 'process raft request'  (duration: 231.810027ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:12.128043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.829948ms","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-04-07T00:53:12.128113Z","caller":"traceutil/trace.go:171","msg":"trace[1628328465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24212; }","duration":"141.933561ms","start":"2026-04-07T00:53:11.986162Z","end":"2026-04-07T00:53:12.128096Z","steps":["trace[1628328465] 'agreement among raft nodes before linearized reading'  (duration: 141.548893ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:12.128043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.45889ms","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-04-07T00:53:12.12822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.943941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"}
{"level":"info","ts":"2026-04-07T00:53:12.12835Z","caller":"traceutil/trace.go:171","msg":"trace[7197376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24212; }","duration":"142.126405ms","start":"2026-04-07T00:53:11.986197Z","end":"2026-04-07T00:53:12.128324Z","steps":["trace[7197376] 'agreement among raft nodes before linearized reading'  (duration: 141.661125ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:12.128267Z","caller":"traceutil/trace.go:171","msg":"trace[893056671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24212; }","duration":"141.730046ms","start":"2026-04-07T00:53:11.986524Z","end":"2026-04-07T00:53:12.128254Z","steps":["trace[893056671] 'agreement among raft nodes before linearized reading'  (duration: 141.343078ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:21.730114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.716202ms","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-04-07T00:53:21.730182Z","caller":"traceutil/trace.go:171","msg":"trace[996846033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24340; }","duration":"138.822115ms","start":"2026-04-07T00:53:21.591344Z","end":"2026-04-07T00:53:21.730166Z","steps":["trace[996846033] 'range keys from in-memory index tree'  (duration: 138.543218ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:21.880994Z","caller":"traceutil/trace.go:171","msg":"trace[166986731] transaction","detail":"{read_only:false; response_revision:24341; number_of_response:1; }","duration":"146.795776ms","start":"2026-04-07T00:53:21.734181Z","end":"2026-04-07T00:53:21.880976Z","steps":["trace[166986731] 'process raft request'  (duration: 146.658633ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:22.186789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.197223ms","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-04-07T00:53:22.186844Z","caller":"traceutil/trace.go:171","msg":"trace[1899481965] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24341; }","duration":"244.298505ms","start":"2026-04-07T00:53:21.942532Z","end":"2026-04-07T00:53:22.186831Z","steps":["trace[1899481965] 'range keys from in-memory index tree'  (duration: 244.032909ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:22.445636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.910337ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4831409558508218502 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:24303 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:453 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-07T00:53:22.445747Z","caller":"traceutil/trace.go:171","msg":"trace[565513539] linearizableReadLoop","detail":"{readStateIndex:25141; appliedIndex:25139; }","duration":"250.013496ms","start":"2026-04-07T00:53:22.195715Z","end":"2026-04-07T00:53:22.445728Z","steps":["trace[565513539] 'read index received'  (duration: 770.177µs)","trace[565513539] 'applied index is now lower than readState.Index'  (duration: 249.242059ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:22.445786Z","caller":"traceutil/trace.go:171","msg":"trace[333139517] transaction","detail":"{read_only:false; response_revision:24343; number_of_response:1; }","duration":"261.819469ms","start":"2026-04-07T00:53:22.18393Z","end":"2026-04-07T00:53:22.445749Z","steps":["trace[333139517] 'process raft request'  (duration: 142.703857ms)","trace[333139517] 'compare'  (duration: 118.55999ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:53:22.44606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.330864ms","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-04-07T00:53:22.446117Z","caller":"traceutil/trace.go:171","msg":"trace[418544552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24343; }","duration":"250.431566ms","start":"2026-04-07T00:53:22.195672Z","end":"2026-04-07T00:53:22.446104Z","steps":["trace[418544552] 'agreement among raft nodes before linearized reading'  (duration: 250.253363ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:22.451787Z","caller":"traceutil/trace.go:171","msg":"trace[23173535] transaction","detail":"{read_only:false; response_revision:24344; number_of_response:1; }","duration":"254.944373ms","start":"2026-04-07T00:53:22.196828Z","end":"2026-04-07T00:53:22.451773Z","steps":["trace[23173535] 'process raft request'  (duration: 254.753199ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:22.451866Z","caller":"traceutil/trace.go:171","msg":"trace[869832125] transaction","detail":"{read_only:false; response_revision:24345; number_of_response:1; }","duration":"253.275678ms","start":"2026-04-07T00:53:22.198572Z","end":"2026-04-07T00:53:22.451848Z","steps":["trace[869832125] 'process raft request'  (duration: 253.154075ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:22.802721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.790969ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4831409558508218505 > lease_revoke:<id:430c9d65434f26ec>","response":"size:28"}
{"level":"info","ts":"2026-04-07T00:53:22.803173Z","caller":"traceutil/trace.go:171","msg":"trace[324392733] transaction","detail":"{read_only:false; response_revision:24348; number_of_response:1; }","duration":"158.953616ms","start":"2026-04-07T00:53:22.644202Z","end":"2026-04-07T00:53:22.803155Z","steps":["trace[324392733] 'process raft request'  (duration: 158.914075ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:22.803621Z","caller":"traceutil/trace.go:171","msg":"trace[1480529388] transaction","detail":"{read_only:false; response_revision:24346; number_of_response:1; }","duration":"354.779691ms","start":"2026-04-07T00:53:22.44883Z","end":"2026-04-07T00:53:22.80361Z","steps":["trace[1480529388] 'process raft request'  (duration: 354.004374ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:22.803767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:22.448809Z","time spent":"354.853043ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:24311 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:368 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-04-07T00:53:22.803926Z","caller":"traceutil/trace.go:171","msg":"trace[648268461] linearizableReadLoop","detail":"{readStateIndex:25144; appliedIndex:25141; }","duration":"358.140003ms","start":"2026-04-07T00:53:22.445774Z","end":"2026-04-07T00:53:22.803914Z","steps":["trace[648268461] 'read index received'  (duration: 5.873466ms)","trace[648268461] 'applied index is now lower than readState.Index'  (duration: 352.265057ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:22.804067Z","caller":"traceutil/trace.go:171","msg":"trace[1980146499] transaction","detail":"{read_only:false; response_revision:24347; number_of_response:1; }","duration":"166.479796ms","start":"2026-04-07T00:53:22.637577Z","end":"2026-04-07T00:53:22.804057Z","steps":["trace[1980146499] 'process raft request'  (duration: 165.484335ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:22.804342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.61597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:53:22.8044Z","caller":"traceutil/trace.go:171","msg":"trace[2140300103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24348; }","duration":"608.717373ms","start":"2026-04-07T00:53:22.195671Z","end":"2026-04-07T00:53:22.804389Z","steps":["trace[2140300103] 'agreement among raft nodes before linearized reading'  (duration: 608.63292ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:22.8044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.285772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"info","ts":"2026-04-07T00:53:22.804502Z","caller":"traceutil/trace.go:171","msg":"trace[491030054] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24348; }","duration":"607.396335ms","start":"2026-04-07T00:53:22.197065Z","end":"2026-04-07T00:53:22.804462Z","steps":["trace[491030054] 'agreement among raft nodes before linearized reading'  (duration: 607.159779ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:22.804521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.743744ms","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-04-07T00:53:22.804557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:22.197055Z","time spent":"607.493176ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-04-07T00:53:22.804569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.455246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-50a9feba-009a-4da0-be17-b23054f621f1\" ","response":"range_response_count:1 size:3761"}
{"level":"info","ts":"2026-04-07T00:53:22.804598Z","caller":"traceutil/trace.go:171","msg":"trace[1647153775] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24348; }","duration":"608.852196ms","start":"2026-04-07T00:53:22.195724Z","end":"2026-04-07T00:53:22.804576Z","steps":["trace[1647153775] 'agreement among raft nodes before linearized reading'  (duration: 608.60672ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:22.804644Z","caller":"traceutil/trace.go:171","msg":"trace[940482938] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-50a9feba-009a-4da0-be17-b23054f621f1; range_end:; response_count:1; response_revision:24348; }","duration":"558.571418ms","start":"2026-04-07T00:53:22.246055Z","end":"2026-04-07T00:53:22.804627Z","steps":["trace[940482938] 'agreement among raft nodes before linearized reading'  (duration: 558.313593ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:22.80468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:22.195644Z","time spent":"609.025ms","remote":"127.0.0.1:41020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-04-07T00:53:22.804701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:22.245968Z","time spent":"558.723751ms","remote":"127.0.0.1:41220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3783,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-50a9feba-009a-4da0-be17-b23054f621f1\" "}
{"level":"warn","ts":"2026-04-07T00:53:22.80465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:22.195709Z","time spent":"608.932797ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"warn","ts":"2026-04-07T00:53:22.804732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.177416ms","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-04-07T00:53:22.805129Z","caller":"traceutil/trace.go:171","msg":"trace[1574416353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24348; }","duration":"597.589814ms","start":"2026-04-07T00:53:22.207524Z","end":"2026-04-07T00:53:22.805114Z","steps":["trace[1574416353] 'agreement among raft nodes before linearized reading'  (duration: 597.142615ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:22.805187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:22.207513Z","time spent":"597.655645ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-04-07T00:53:22.964653Z","caller":"traceutil/trace.go:171","msg":"trace[557898424] transaction","detail":"{read_only:false; response_revision:24349; number_of_response:1; }","duration":"154.409528ms","start":"2026-04-07T00:53:22.810203Z","end":"2026-04-07T00:53:22.964612Z","steps":["trace[557898424] 'process raft request'  (duration: 130.593307ms)","trace[557898424] 'compare'  (duration: 23.351881ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:23.288595Z","caller":"traceutil/trace.go:171","msg":"trace[728826191] transaction","detail":"{read_only:false; response_revision:24350; number_of_response:1; }","duration":"478.092213ms","start":"2026-04-07T00:53:22.810475Z","end":"2026-04-07T00:53:23.288567Z","steps":["trace[728826191] 'process raft request'  (duration: 476.602332ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:23.28878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:22.810457Z","time spent":"478.225236ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:24308 > 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-04-07T00:53:23.289043Z","caller":"traceutil/trace.go:171","msg":"trace[1250456191] transaction","detail":"{read_only:false; response_revision:24354; number_of_response:1; }","duration":"234.359322ms","start":"2026-04-07T00:53:23.054666Z","end":"2026-04-07T00:53:23.289025Z","steps":["trace[1250456191] 'process raft request'  (duration: 234.30497ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:23.289106Z","caller":"traceutil/trace.go:171","msg":"trace[1738691240] linearizableReadLoop","detail":"{readStateIndex:25150; appliedIndex:25147; }","duration":"478.256717ms","start":"2026-04-07T00:53:22.810825Z","end":"2026-04-07T00:53:23.289081Z","steps":["trace[1738691240] 'read index received'  (duration: 129.986484ms)","trace[1738691240] 'applied index is now lower than readState.Index'  (duration: 348.268703ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:23.28915Z","caller":"traceutil/trace.go:171","msg":"trace[1045322115] transaction","detail":"{read_only:false; response_revision:24353; number_of_response:1; }","duration":"267.662285ms","start":"2026-04-07T00:53:23.021475Z","end":"2026-04-07T00:53:23.289137Z","steps":["trace[1045322115] 'process raft request'  (duration: 267.44325ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:23.289234Z","caller":"traceutil/trace.go:171","msg":"trace[622559908] transaction","detail":"{read_only:false; response_revision:24351; number_of_response:1; }","duration":"478.648806ms","start":"2026-04-07T00:53:22.810572Z","end":"2026-04-07T00:53:23.289221Z","steps":["trace[622559908] 'process raft request'  (duration: 477.94625ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:23.289346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:22.810565Z","time spent":"478.706627ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:24313 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-04-07T00:53:23.289396Z","caller":"traceutil/trace.go:171","msg":"trace[1768836767] transaction","detail":"{read_only:false; response_revision:24352; number_of_response:1; }","duration":"404.672771ms","start":"2026-04-07T00:53:22.884714Z","end":"2026-04-07T00:53:23.289387Z","steps":["trace[1768836767] 'process raft request'  (duration: 404.128079ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:23.289464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:22.884699Z","time spent":"404.733642ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/capi-kubeadm-control-plane-system/kubeadm-control-plane-manager-leader-election-capi\" mod_revision:24254 > 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-04-07T00:53:23.289358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.524823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"}
{"level":"info","ts":"2026-04-07T00:53:23.289574Z","caller":"traceutil/trace.go:171","msg":"trace[1419762081] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24354; }","duration":"478.751518ms","start":"2026-04-07T00:53:22.810809Z","end":"2026-04-07T00:53:23.289561Z","steps":["trace[1419762081] 'agreement among raft nodes before linearized reading'  (duration: 478.423681ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:23.289624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:22.810803Z","time spent":"478.808279ms","remote":"127.0.0.1:41220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "}
{"level":"warn","ts":"2026-04-07T00:53:23.289743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.200824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"}
{"level":"warn","ts":"2026-04-07T00:53:23.289585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.38124ms","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-04-07T00:53:23.28978Z","caller":"traceutil/trace.go:171","msg":"trace[2000703736] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:24354; }","duration":"161.237335ms","start":"2026-04-07T00:53:23.128535Z","end":"2026-04-07T00:53:23.289772Z","steps":["trace[2000703736] 'agreement among raft nodes before linearized reading'  (duration: 161.067041ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:23.2898Z","caller":"traceutil/trace.go:171","msg":"trace[1992712963] 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:24354; }","duration":"429.596615ms","start":"2026-04-07T00:53:22.860191Z","end":"2026-04-07T00:53:23.289788Z","steps":["trace[1992712963] 'agreement among raft nodes before linearized reading'  (duration: 429.36448ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:23.289855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:22.860168Z","time spent":"429.668447ms","remote":"127.0.0.1:54180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":28,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "}
{"level":"warn","ts":"2026-04-07T00:53:23.289941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.538078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:53:23.290006Z","caller":"traceutil/trace.go:171","msg":"trace[1827451671] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:24354; }","duration":"173.577299ms","start":"2026-04-07T00:53:23.116393Z","end":"2026-04-07T00:53:23.28997Z","steps":["trace[1827451671] 'agreement among raft nodes before linearized reading'  (duration: 173.521048ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:25.520339Z","caller":"traceutil/trace.go:171","msg":"trace[201331877] linearizableReadLoop","detail":"{readStateIndex:25167; appliedIndex:25166; }","duration":"223.622492ms","start":"2026-04-07T00:53:25.296694Z","end":"2026-04-07T00:53:25.520317Z","steps":["trace[201331877] 'read index received'  (duration: 223.462039ms)","trace[201331877] 'applied index is now lower than readState.Index'  (duration: 159.283µs)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:25.520406Z","caller":"traceutil/trace.go:171","msg":"trace[1829801966] transaction","detail":"{read_only:false; response_revision:24368; number_of_response:1; }","duration":"223.892977ms","start":"2026-04-07T00:53:25.296478Z","end":"2026-04-07T00:53:25.520371Z","steps":["trace[1829801966] 'process raft request'  (duration: 223.676663ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:25.520645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.922078ms","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-04-07T00:53:25.520763Z","caller":"traceutil/trace.go:171","msg":"trace[952567491] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24368; }","duration":"224.125202ms","start":"2026-04-07T00:53:25.296608Z","end":"2026-04-07T00:53:25.520734Z","steps":["trace[952567491] 'agreement among raft nodes before linearized reading'  (duration: 223.811035ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:25.561257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.306574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:53:25.561328Z","caller":"traceutil/trace.go:171","msg":"trace[1824727625] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:24369; }","duration":"208.422306ms","start":"2026-04-07T00:53:25.35289Z","end":"2026-04-07T00:53:25.561313Z","steps":["trace[1824727625] 'agreement among raft nodes before linearized reading'  (duration: 208.254562ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:25.561322Z","caller":"traceutil/trace.go:171","msg":"trace[1835603748] transaction","detail":"{read_only:false; response_revision:24369; number_of_response:1; }","duration":"260.166985ms","start":"2026-04-07T00:53:25.301108Z","end":"2026-04-07T00:53:25.561275Z","steps":["trace[1835603748] 'process raft request'  (duration: 259.887659ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:25.852397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.181199ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4831409558508218563 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:24356 > success:<request_put:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-07T00:53:25.852702Z","caller":"traceutil/trace.go:171","msg":"trace[610157771] transaction","detail":"{read_only:false; response_revision:24371; number_of_response:1; }","duration":"276.424933ms","start":"2026-04-07T00:53:25.576224Z","end":"2026-04-07T00:53:25.852649Z","steps":["trace[610157771] 'process raft request'  (duration: 124.865976ms)","trace[610157771] 'compare'  (duration: 150.845031ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:53:28.70592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.406865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-04-07T00:53:28.706046Z","caller":"traceutil/trace.go:171","msg":"trace[1572755045] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:24411; }","duration":"243.60632ms","start":"2026-04-07T00:53:28.462416Z","end":"2026-04-07T00:53:28.706023Z","steps":["trace[1572755045] 'count revisions from in-memory index tree'  (duration: 243.280513ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:28.706475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.865499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:53:28.706579Z","caller":"traceutil/trace.go:171","msg":"trace[1801277044] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24411; }","duration":"240.433281ms","start":"2026-04-07T00:53:28.466123Z","end":"2026-04-07T00:53:28.706557Z","steps":["trace[1801277044] 'range keys from in-memory index tree'  (duration: 239.227206ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:28.706768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.451375ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4831409558508218650 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:24375 > 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:18"}
{"level":"info","ts":"2026-04-07T00:53:28.707404Z","caller":"traceutil/trace.go:171","msg":"trace[1814843063] transaction","detail":"{read_only:false; response_revision:24412; number_of_response:1; }","duration":"238.099352ms","start":"2026-04-07T00:53:28.469253Z","end":"2026-04-07T00:53:28.707353Z","steps":["trace[1814843063] 'process raft request'  (duration: 42.258186ms)","trace[1814843063] 'compare'  (duration: 194.053207ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:28.707415Z","caller":"traceutil/trace.go:171","msg":"trace[671390308] linearizableReadLoop","detail":"{readStateIndex:25214; appliedIndex:25212; }","duration":"222.675952ms","start":"2026-04-07T00:53:28.484716Z","end":"2026-04-07T00:53:28.707392Z","steps":["trace[671390308] 'read index received'  (duration: 26.948898ms)","trace[671390308] 'applied index is now lower than readState.Index'  (duration: 195.725554ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:28.707679Z","caller":"traceutil/trace.go:171","msg":"trace[1007005348] transaction","detail":"{read_only:false; response_revision:24413; number_of_response:1; }","duration":"233.267218ms","start":"2026-04-07T00:53:28.474297Z","end":"2026-04-07T00:53:28.707564Z","steps":["trace[1007005348] 'process raft request'  (duration: 232.973712ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:28.707725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.981278ms","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-04-07T00:53:28.707942Z","caller":"traceutil/trace.go:171","msg":"trace[1901944482] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24413; }","duration":"223.221924ms","start":"2026-04-07T00:53:28.484688Z","end":"2026-04-07T00:53:28.70791Z","steps":["trace[1901944482] 'agreement among raft nodes before linearized reading'  (duration: 222.764004ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:28.967807Z","caller":"traceutil/trace.go:171","msg":"trace[507278936] linearizableReadLoop","detail":"{readStateIndex:25215; appliedIndex:25214; }","duration":"252.91684ms","start":"2026-04-07T00:53:28.714866Z","end":"2026-04-07T00:53:28.967782Z","steps":["trace[507278936] 'read index received'  (duration: 252.687665ms)","trace[507278936] 'applied index is now lower than readState.Index'  (duration: 227.985µs)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:28.968122Z","caller":"traceutil/trace.go:171","msg":"trace[1267537447] transaction","detail":"{read_only:false; response_revision:24414; number_of_response:1; }","duration":"253.822929ms","start":"2026-04-07T00:53:28.714281Z","end":"2026-04-07T00:53:28.968104Z","steps":["trace[1267537447] 'process raft request'  (duration: 253.343388ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:28.968563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.676056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18367"}
{"level":"info","ts":"2026-04-07T00:53:28.968613Z","caller":"traceutil/trace.go:171","msg":"trace[1650829581] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24414; }","duration":"253.785968ms","start":"2026-04-07T00:53:28.714815Z","end":"2026-04-07T00:53:28.968601Z","steps":["trace[1650829581] 'agreement among raft nodes before linearized reading'  (duration: 253.569383ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:28.980622Z","caller":"traceutil/trace.go:171","msg":"trace[1766130955] transaction","detail":"{read_only:false; response_revision:24415; number_of_response:1; }","duration":"155.590433ms","start":"2026-04-07T00:53:28.825011Z","end":"2026-04-07T00:53:28.980601Z","steps":["trace[1766130955] 'process raft request'  (duration: 155.318327ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:28.980732Z","caller":"traceutil/trace.go:171","msg":"trace[1979409256] transaction","detail":"{read_only:false; response_revision:24416; number_of_response:1; }","duration":"155.579143ms","start":"2026-04-07T00:53:28.82512Z","end":"2026-04-07T00:53:28.980699Z","steps":["trace[1979409256] 'process raft request'  (duration: 155.380639ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:28.980745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.352771ms","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-04-07T00:53:28.980822Z","caller":"traceutil/trace.go:171","msg":"trace[232452756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24416; }","duration":"150.477923ms","start":"2026-04-07T00:53:28.830327Z","end":"2026-04-07T00:53:28.980805Z","steps":["trace[232452756] 'agreement among raft nodes before linearized reading'  (duration: 150.228668ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:57.882928Z","caller":"traceutil/trace.go:171","msg":"trace[845703371] linearizableReadLoop","detail":"{readStateIndex:25457; appliedIndex:25456; }","duration":"154.234975ms","start":"2026-04-07T00:53:57.728672Z","end":"2026-04-07T00:53:57.882907Z","steps":["trace[845703371] 'read index received'  (duration: 154.154453ms)","trace[845703371] 'applied index is now lower than readState.Index'  (duration: 79.852µs)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:57.883197Z","caller":"traceutil/trace.go:171","msg":"trace[2120575796] transaction","detail":"{read_only:false; response_revision:24649; number_of_response:1; }","duration":"236.317944ms","start":"2026-04-07T00:53:57.646867Z","end":"2026-04-07T00:53:57.883185Z","steps":["trace[2120575796] 'process raft request'  (duration: 235.72607ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:57.88342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.744826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"}
{"level":"info","ts":"2026-04-07T00:53:57.883444Z","caller":"traceutil/trace.go:171","msg":"trace[854342520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24649; }","duration":"154.788267ms","start":"2026-04-07T00:53:57.728649Z","end":"2026-04-07T00:53:57.883438Z","steps":["trace[854342520] 'agreement among raft nodes before linearized reading'  (duration: 154.679315ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:57.913898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.167713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"}
{"level":"info","ts":"2026-04-07T00:53:57.914032Z","caller":"traceutil/trace.go:171","msg":"trace[476462402] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24650; }","duration":"154.364198ms","start":"2026-04-07T00:53:57.759645Z","end":"2026-04-07T00:53:57.914009Z","steps":["trace[476462402] 'agreement among raft nodes before linearized reading'  (duration: 154.04947ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:58.175611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.707013ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4831409558508219256 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:24635 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-07T00:53:58.175725Z","caller":"traceutil/trace.go:171","msg":"trace[978419007] transaction","detail":"{read_only:false; response_revision:24651; number_of_response:1; }","duration":"277.108318ms","start":"2026-04-07T00:53:57.898599Z","end":"2026-04-07T00:53:58.175707Z","steps":["trace[978419007] 'process raft request'  (duration: 151.218671ms)","trace[978419007] 'compare'  (duration: 125.309615ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:58.269436Z","caller":"traceutil/trace.go:171","msg":"trace[2110785954] transaction","detail":"{read_only:false; response_revision:24652; number_of_response:1; }","duration":"350.323686ms","start":"2026-04-07T00:53:57.919076Z","end":"2026-04-07T00:53:58.269399Z","steps":["trace[2110785954] 'process raft request'  (duration: 350.092051ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:58.269644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:57.91904Z","time spent":"350.49093ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:24636 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:464 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"info","ts":"2026-04-07T00:53:58.26965Z","caller":"traceutil/trace.go:171","msg":"trace[228852487] linearizableReadLoop","detail":"{readStateIndex:25461; appliedIndex:25459; }","duration":"215.550718ms","start":"2026-04-07T00:53:58.054054Z","end":"2026-04-07T00:53:58.269604Z","steps":["trace[228852487] 'read index received'  (duration: 215.087808ms)","trace[228852487] 'applied index is now lower than readState.Index'  (duration: 460.679µs)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:58.269678Z","caller":"traceutil/trace.go:171","msg":"trace[1331040726] transaction","detail":"{read_only:false; response_revision:24653; number_of_response:1; }","duration":"292.946376ms","start":"2026-04-07T00:53:57.976685Z","end":"2026-04-07T00:53:58.269631Z","steps":["trace[1331040726] 'process raft request'  (duration: 292.66692ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:58.269924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.964511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:53:58.269976Z","caller":"traceutil/trace.go:171","msg":"trace[576254853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24653; }","duration":"106.081073ms","start":"2026-04-07T00:53:58.163879Z","end":"2026-04-07T00:53:58.26996Z","steps":["trace[576254853] 'agreement among raft nodes before linearized reading'  (duration: 105.91726ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:58.270083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.009289ms","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-04-07T00:53:58.270287Z","caller":"traceutil/trace.go:171","msg":"trace[907119199] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24653; }","duration":"216.226473ms","start":"2026-04-07T00:53:58.054039Z","end":"2026-04-07T00:53:58.270265Z","steps":["trace[907119199] 'agreement among raft nodes before linearized reading'  (duration: 215.746083ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:58.484823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.119628ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4831409558508219263 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:24638 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-07T00:53:58.485206Z","caller":"traceutil/trace.go:171","msg":"trace[1428373185] linearizableReadLoop","detail":"{readStateIndex:25462; appliedIndex:25461; }","duration":"169.816228ms","start":"2026-04-07T00:53:58.315347Z","end":"2026-04-07T00:53:58.485163Z","steps":["trace[1428373185] 'read index received'  (duration: 32.259251ms)","trace[1428373185] 'applied index is now lower than readState.Index'  (duration: 137.550337ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:58.485323Z","caller":"traceutil/trace.go:171","msg":"trace[1303092675] transaction","detail":"{read_only:false; response_revision:24654; number_of_response:1; }","duration":"209.777604ms","start":"2026-04-07T00:53:58.275529Z","end":"2026-04-07T00:53:58.485306Z","steps":["trace[1303092675] 'process raft request'  (duration: 72.055813ms)","trace[1303092675] 'compare'  (duration: 136.887053ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:53:58.485683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.33049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-04-07T00:53:58.485748Z","caller":"traceutil/trace.go:171","msg":"trace[340955599] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24655; }","duration":"170.450552ms","start":"2026-04-07T00:53:58.315283Z","end":"2026-04-07T00:53:58.485733Z","steps":["trace[340955599] 'agreement among raft nodes before linearized reading'  (duration: 170.33116ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:58.485824Z","caller":"traceutil/trace.go:171","msg":"trace[846232384] transaction","detail":"{read_only:false; response_revision:24655; number_of_response:1; }","duration":"144.472096ms","start":"2026-04-07T00:53:58.341317Z","end":"2026-04-07T00:53:58.485789Z","steps":["trace[846232384] 'process raft request'  (duration: 143.626837ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:58.497184Z","caller":"traceutil/trace.go:171","msg":"trace[1433419742] transaction","detail":"{read_only:false; response_revision:24656; number_of_response:1; }","duration":"145.721753ms","start":"2026-04-07T00:53:58.351397Z","end":"2026-04-07T00:53:58.497119Z","steps":["trace[1433419742] 'process raft request'  (duration: 145.401426ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:58.497216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.994734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"}
{"level":"info","ts":"2026-04-07T00:53:58.497299Z","caller":"traceutil/trace.go:171","msg":"trace[851927317] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:24656; }","duration":"150.100526ms","start":"2026-04-07T00:53:58.347183Z","end":"2026-04-07T00:53:58.497284Z","steps":["trace[851927317] 'agreement among raft nodes before linearized reading'  (duration: 149.79213ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:59.108883Z","caller":"traceutil/trace.go:171","msg":"trace[692610363] transaction","detail":"{read_only:false; response_revision:24657; number_of_response:1; }","duration":"281.816199ms","start":"2026-04-07T00:53:58.82704Z","end":"2026-04-07T00:53:59.108856Z","steps":["trace[692610363] 'process raft request'  (duration: 281.649755ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:59.122869Z","caller":"traceutil/trace.go:171","msg":"trace[1217857658] transaction","detail":"{read_only:false; response_revision:24658; number_of_response:1; }","duration":"294.354946ms","start":"2026-04-07T00:53:58.828345Z","end":"2026-04-07T00:53:59.1227Z","steps":["trace[1217857658] 'process raft request'  (duration: 293.927797ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:59.444051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.100915ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4831409558508219274 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:24646 > 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\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-07T00:53:59.444154Z","caller":"traceutil/trace.go:171","msg":"trace[1457395954] linearizableReadLoop","detail":"{readStateIndex:25469; appliedIndex:25468; }","duration":"228.323292ms","start":"2026-04-07T00:53:59.215814Z","end":"2026-04-07T00:53:59.444137Z","steps":["trace[1457395954] 'read index received'  (duration: 65.020733ms)","trace[1457395954] 'applied index is now lower than readState.Index'  (duration: 163.301869ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:59.444228Z","caller":"traceutil/trace.go:171","msg":"trace[458627065] transaction","detail":"{read_only:false; response_revision:24661; number_of_response:1; }","duration":"298.118648ms","start":"2026-04-07T00:53:59.146101Z","end":"2026-04-07T00:53:59.444219Z","steps":["trace[458627065] 'process raft request'  (duration: 134.758238ms)","trace[458627065] 'compare'  (duration: 162.713637ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:53:59.444502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.631151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:53:59.444536Z","caller":"traceutil/trace.go:171","msg":"trace[1120151325] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:24661; }","duration":"138.683532ms","start":"2026-04-07T00:53:59.305841Z","end":"2026-04-07T00:53:59.444525Z","steps":["trace[1120151325] 'agreement among raft nodes before linearized reading'  (duration: 138.572909ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:59.444615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.331435ms","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-04-07T00:53:59.444686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.936669ms","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-04-07T00:53:59.444735Z","caller":"traceutil/trace.go:171","msg":"trace[891549585] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:24661; }","duration":"146.528789ms","start":"2026-04-07T00:53:59.298178Z","end":"2026-04-07T00:53:59.444706Z","steps":["trace[891549585] 'agreement among raft nodes before linearized reading'  (duration: 146.247534ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:59.444708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.872944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"info","ts":"2026-04-07T00:53:59.444777Z","caller":"traceutil/trace.go:171","msg":"trace[1061423442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24661; }","duration":"225.053651ms","start":"2026-04-07T00:53:59.2197Z","end":"2026-04-07T00:53:59.444753Z","steps":["trace[1061423442] 'agreement among raft nodes before linearized reading'  (duration: 224.578881ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:59.44483Z","caller":"traceutil/trace.go:171","msg":"trace[360659872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24661; }","duration":"229.044338ms","start":"2026-04-07T00:53:59.215761Z","end":"2026-04-07T00:53:59.444805Z","steps":["trace[360659872] 'agreement among raft nodes before linearized reading'  (duration: 228.67791ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:59.739879Z","caller":"traceutil/trace.go:171","msg":"trace[1112544258] transaction","detail":"{read_only:false; response_revision:24662; number_of_response:1; }","duration":"290.436072ms","start":"2026-04-07T00:53:59.449414Z","end":"2026-04-07T00:53:59.73985Z","steps":["trace[1112544258] 'process raft request'  (duration: 212.713367ms)","trace[1112544258] 'compare'  (duration: 77.097272ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:59.740083Z","caller":"traceutil/trace.go:171","msg":"trace[465161300] linearizableReadLoop","detail":"{readStateIndex:25471; appliedIndex:25469; }","duration":"198.368509ms","start":"2026-04-07T00:53:59.541691Z","end":"2026-04-07T00:53:59.74006Z","steps":["trace[465161300] 'read index received'  (duration: 120.454661ms)","trace[465161300] 'applied index is now lower than readState.Index'  (duration: 77.912668ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:53:59.740315Z","caller":"traceutil/trace.go:171","msg":"trace[842383745] transaction","detail":"{read_only:false; response_revision:24663; number_of_response:1; }","duration":"290.702307ms","start":"2026-04-07T00:53:59.449599Z","end":"2026-04-07T00:53:59.740302Z","steps":["trace[842383745] 'process raft request'  (duration: 290.195487ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:59.740716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.993883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-04-07T00:53:59.740748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.384419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"}
{"level":"info","ts":"2026-04-07T00:53:59.740767Z","caller":"traceutil/trace.go:171","msg":"trace[2084335690] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:24663; }","duration":"199.122056ms","start":"2026-04-07T00:53:59.54163Z","end":"2026-04-07T00:53:59.740752Z","steps":["trace[2084335690] 'agreement among raft nodes before linearized reading'  (duration: 199.013024ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:53:59.740794Z","caller":"traceutil/trace.go:171","msg":"trace[1481170130] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24663; }","duration":"176.498401ms","start":"2026-04-07T00:53:59.564283Z","end":"2026-04-07T00:53:59.740781Z","steps":["trace[1481170130] 'agreement among raft nodes before linearized reading'  (duration: 176.104942ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:53:59.741336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.30187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"}
{"level":"info","ts":"2026-04-07T00:53:59.741521Z","caller":"traceutil/trace.go:171","msg":"trace[1808666358] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24663; }","duration":"128.553425ms","start":"2026-04-07T00:53:59.612912Z","end":"2026-04-07T00:53:59.741465Z","steps":["trace[1808666358] 'agreement among raft nodes before linearized reading'  (duration: 127.920492ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:54:00.374616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.425482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"}
{"level":"info","ts":"2026-04-07T00:54:00.374719Z","caller":"traceutil/trace.go:171","msg":"trace[1427305806] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24666; }","duration":"100.580426ms","start":"2026-04-07T00:54:00.274116Z","end":"2026-04-07T00:54:00.374697Z","steps":["trace[1427305806] 'range keys from in-memory index tree'  (duration: 100.311299ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:54:00.374809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.459011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"}
{"level":"info","ts":"2026-04-07T00:54:00.374883Z","caller":"traceutil/trace.go:171","msg":"trace[42880058] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:24666; }","duration":"113.547683ms","start":"2026-04-07T00:54:00.261314Z","end":"2026-04-07T00:54:00.374862Z","steps":["trace[42880058] 'range keys from in-memory index tree'  (duration: 113.214346ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:54:00.59286Z","caller":"traceutil/trace.go:171","msg":"trace[538492918] linearizableReadLoop","detail":"{readStateIndex:25476; appliedIndex:25475; }","duration":"102.470077ms","start":"2026-04-07T00:54:00.490358Z","end":"2026-04-07T00:54:00.592828Z","steps":["trace[538492918] 'read index received'  (duration: 74.236411ms)","trace[538492918] 'applied index is now lower than readState.Index'  (duration: 28.232226ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:54:00.593159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.783733ms","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-04-07T00:54:00.593193Z","caller":"traceutil/trace.go:171","msg":"trace[238677404] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24668; }","duration":"102.903865ms","start":"2026-04-07T00:54:00.490277Z","end":"2026-04-07T00:54:00.593181Z","steps":["trace[238677404] 'agreement among raft nodes before linearized reading'  (duration: 102.67489ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:54:00.593192Z","caller":"traceutil/trace.go:171","msg":"trace[344370849] transaction","detail":"{read_only:false; response_revision:24668; number_of_response:1; }","duration":"213.524255ms","start":"2026-04-07T00:54:00.379629Z","end":"2026-04-07T00:54:00.593153Z","steps":["trace[344370849] 'process raft request'  (duration: 184.861451ms)","trace[344370849] 'compare'  (duration: 28.187784ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:54:01.376912Z","caller":"traceutil/trace.go:171","msg":"trace[64854550] transaction","detail":"{read_only:false; response_revision:24673; number_of_response:1; }","duration":"248.421943ms","start":"2026-04-07T00:54:01.128466Z","end":"2026-04-07T00:54:01.376888Z","steps":["trace[64854550] 'process raft request'  (duration: 248.328661ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:54:01.376936Z","caller":"traceutil/trace.go:171","msg":"trace[1470429145] transaction","detail":"{read_only:false; response_revision:24672; number_of_response:1; }","duration":"250.14787ms","start":"2026-04-07T00:54:01.126746Z","end":"2026-04-07T00:54:01.376893Z","steps":["trace[1470429145] 'process raft request'  (duration: 217.226194ms)","trace[1470429145] 'compare'  (duration: 32.524067ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:54:01.377207Z","caller":"traceutil/trace.go:171","msg":"trace[738163310] linearizableReadLoop","detail":"{readStateIndex:25482; appliedIndex:25478; }","duration":"215.790074ms","start":"2026-04-07T00:54:01.161392Z","end":"2026-04-07T00:54:01.377182Z","steps":["trace[738163310] 'read index received'  (duration: 40.349575ms)","trace[738163310] 'applied index is now lower than readState.Index'  (duration: 175.437419ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:54:01.37731Z","caller":"traceutil/trace.go:171","msg":"trace[1938540685] transaction","detail":"{read_only:false; response_revision:24674; number_of_response:1; }","duration":"248.712559ms","start":"2026-04-07T00:54:01.128584Z","end":"2026-04-07T00:54:01.377296Z","steps":["trace[1938540685] 'process raft request'  (duration: 248.26301ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:54:01.377386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.986038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:54:01.377971Z","caller":"traceutil/trace.go:171","msg":"trace[1886656707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24674; }","duration":"216.623311ms","start":"2026-04-07T00:54:01.16133Z","end":"2026-04-07T00:54:01.377953Z","steps":["trace[1886656707] 'agreement among raft nodes before linearized reading'  (duration: 215.971677ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:54:01.59792Z","caller":"traceutil/trace.go:171","msg":"trace[394698652] transaction","detail":"{read_only:false; response_revision:24675; number_of_response:1; }","duration":"144.628049ms","start":"2026-04-07T00:54:01.453267Z","end":"2026-04-07T00:54:01.597895Z","steps":["trace[394698652] 'process raft request'  (duration: 72.539004ms)","trace[394698652] 'compare'  (duration: 71.963382ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:54:01.835071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.424578ms","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-04-07T00:54:01.835145Z","caller":"traceutil/trace.go:171","msg":"trace[308023420] 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:24675; }","duration":"119.51963ms","start":"2026-04-07T00:54:01.715605Z","end":"2026-04-07T00:54:01.835125Z","steps":["trace[308023420] 'range keys from in-memory index tree'  (duration: 119.265535ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:54:01.95713Z","caller":"traceutil/trace.go:171","msg":"trace[883928856] transaction","detail":"{read_only:false; response_revision:24676; number_of_response:1; }","duration":"117.714602ms","start":"2026-04-07T00:54:01.839371Z","end":"2026-04-07T00:54:01.957086Z","steps":["trace[883928856] 'process raft request'  (duration: 117.538878ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:54:01.979243Z","caller":"traceutil/trace.go:171","msg":"trace[1670380024] transaction","detail":"{read_only:false; response_revision:24677; number_of_response:1; }","duration":"138.424226ms","start":"2026-04-07T00:54:01.840776Z","end":"2026-04-07T00:54:01.9792Z","steps":["trace[1670380024] 'process raft request'  (duration: 138.14585ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:54:02.200111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.009976ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4831409558508219320 > lease_revoke:<id:430c9d65434f2a7d>","response":"size:28"}
{"level":"info","ts":"2026-04-07T00:54:02.200299Z","caller":"traceutil/trace.go:171","msg":"trace[814711525] linearizableReadLoop","detail":"{readStateIndex:25485; appliedIndex:25484; }","duration":"359.235558ms","start":"2026-04-07T00:54:01.841049Z","end":"2026-04-07T00:54:02.200284Z","steps":["trace[814711525] 'read index received'  (duration: 116.400345ms)","trace[814711525] 'applied index is now lower than readState.Index'  (duration: 242.834453ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:54:02.200734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.675657ms","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-04-07T00:54:02.200754Z","caller":"traceutil/trace.go:171","msg":"trace[1283675704] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24677; }","duration":"359.736628ms","start":"2026-04-07T00:54:01.841011Z","end":"2026-04-07T00:54:02.200748Z","steps":["trace[1283675704] 'agreement among raft nodes before linearized reading'  (duration: 359.680777ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:54:02.200775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:01.841002Z","time spent":"359.767209ms","remote":"127.0.0.1:41234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "}
{"level":"info","ts":"2026-04-07T00:54:02.200921Z","caller":"traceutil/trace.go:171","msg":"trace[1738875984] transaction","detail":"{read_only:false; response_revision:24678; number_of_response:1; }","duration":"214.526557ms","start":"2026-04-07T00:54:01.986364Z","end":"2026-04-07T00:54:02.200891Z","steps":["trace[1738875984] 'process raft request'  (duration: 213.844843ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:54:02.351811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.638503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:54:02.351904Z","caller":"traceutil/trace.go:171","msg":"trace[1622123331] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:24678; }","duration":"322.787817ms","start":"2026-04-07T00:54:02.029091Z","end":"2026-04-07T00:54:02.351879Z","steps":["trace[1622123331] 'agreement among raft nodes before linearized reading'  (duration: 171.767631ms)","trace[1622123331] 'count revisions from in-memory index tree'  (duration: 150.870142ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:54:02.351942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:02.029071Z","time spent":"322.861438ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "}
{"level":"warn","ts":"2026-04-07T00:54:02.352245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.387849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:54:02.352264Z","caller":"traceutil/trace.go:171","msg":"trace[1639329894] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:24678; }","duration":"169.433461ms","start":"2026-04-07T00:54:02.182823Z","end":"2026-04-07T00:54:02.352256Z","steps":["trace[1639329894] 'agreement among raft nodes before linearized reading'  (duration: 18.15391ms)","trace[1639329894] 'count revisions from in-memory index tree'  (duration: 151.25181ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:54:02.352453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.88246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"}
{"level":"info","ts":"2026-04-07T00:54:02.352474Z","caller":"traceutil/trace.go:171","msg":"trace[196426769] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24678; }","duration":"183.929301ms","start":"2026-04-07T00:54:02.168534Z","end":"2026-04-07T00:54:02.352463Z","steps":["trace[196426769] 'agreement among raft nodes before linearized reading'  (duration: 32.453746ms)","trace[196426769] 'range keys from in-memory index tree'  (duration: 151.374573ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:54:02.352563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.57789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:54:02.352581Z","caller":"traceutil/trace.go:171","msg":"trace[272202283] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24678; }","duration":"146.636072ms","start":"2026-04-07T00:54:02.205938Z","end":"2026-04-07T00:54:02.352574Z","steps":["trace[272202283] 'range keys from in-memory index tree'  (duration: 146.481148ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:54:02.352714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.843ms","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-04-07T00:54:02.352732Z","caller":"traceutil/trace.go:171","msg":"trace[1574854105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24678; }","duration":"142.904632ms","start":"2026-04-07T00:54:02.209822Z","end":"2026-04-07T00:54:02.352727Z","steps":["trace[1574854105] 'range keys from in-memory index tree'  (duration: 142.745869ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:54:02.498162Z","caller":"traceutil/trace.go:171","msg":"trace[1715509988] transaction","detail":"{read_only:false; response_revision:24679; number_of_response:1; }","duration":"126.414248ms","start":"2026-04-07T00:54:02.371711Z","end":"2026-04-07T00:54:02.498126Z","steps":["trace[1715509988] 'process raft request'  (duration: 126.114352ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:54:02.536628Z","caller":"traceutil/trace.go:171","msg":"trace[196966772] linearizableReadLoop","detail":"{readStateIndex:25489; appliedIndex:25487; }","duration":"163.641867ms","start":"2026-04-07T00:54:02.372962Z","end":"2026-04-07T00:54:02.536604Z","steps":["trace[196966772] 'read index received'  (duration: 124.906917ms)","trace[196966772] 'applied index is now lower than readState.Index'  (duration: 38.73386ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:54:02.536773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.78296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:54:02.536732Z","caller":"traceutil/trace.go:171","msg":"trace[1041168600] transaction","detail":"{read_only:false; response_revision:24681; number_of_response:1; }","duration":"156.939663ms","start":"2026-04-07T00:54:02.379753Z","end":"2026-04-07T00:54:02.536693Z","steps":["trace[1041168600] 'process raft request'  (duration: 156.81812ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:54:02.536805Z","caller":"traceutil/trace.go:171","msg":"trace[1596555171] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24681; }","duration":"163.861561ms","start":"2026-04-07T00:54:02.372931Z","end":"2026-04-07T00:54:02.536792Z","steps":["trace[1596555171] 'agreement among raft nodes before linearized reading'  (duration: 163.760119ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:54:02.536663Z","caller":"traceutil/trace.go:171","msg":"trace[886466397] transaction","detail":"{read_only:false; response_revision:24680; number_of_response:1; }","duration":"164.28808ms","start":"2026-04-07T00:54:02.372357Z","end":"2026-04-07T00:54:02.536645Z","steps":["trace[886466397] 'process raft request'  (duration: 164.087595ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:54:02.777846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.127848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"}
{"level":"info","ts":"2026-04-07T00:54:02.777975Z","caller":"traceutil/trace.go:171","msg":"trace[1868104593] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24681; }","duration":"179.302362ms","start":"2026-04-07T00:54:02.598631Z","end":"2026-04-07T00:54:02.777933Z","steps":["trace[1868104593] 'range keys from in-memory index tree'  (duration: 178.917404ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:54:02.778479Z","caller":"traceutil/trace.go:171","msg":"trace[1516569986] linearizableReadLoop","detail":"{readStateIndex:25491; appliedIndex:25490; }","duration":"112.253145ms","start":"2026-04-07T00:54:02.666196Z","end":"2026-04-07T00:54:02.778449Z","steps":["trace[1516569986] 'read index received'  (duration: 69.187443ms)","trace[1516569986] 'applied index is now lower than readState.Index'  (duration: 43.064212ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:54:02.778629Z","caller":"traceutil/trace.go:171","msg":"trace[502975940] transaction","detail":"{read_only:false; response_revision:24682; number_of_response:1; }","duration":"176.445551ms","start":"2026-04-07T00:54:02.602167Z","end":"2026-04-07T00:54:02.778612Z","steps":["trace[502975940] 'process raft request'  (duration: 133.195184ms)","trace[502975940] 'compare'  (duration: 42.94519ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T00:54:02.778923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.733275ms","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-04-07T00:54:02.778963Z","caller":"traceutil/trace.go:171","msg":"trace[1211462358] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24682; }","duration":"112.768956ms","start":"2026-04-07T00:54:02.666179Z","end":"2026-04-07T00:54:02.778948Z","steps":["trace[1211462358] 'agreement among raft nodes before linearized reading'  (duration: 112.671814ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:54:03.00377Z","caller":"traceutil/trace.go:171","msg":"trace[1169459942] transaction","detail":"{read_only:false; response_revision:24684; number_of_response:1; }","duration":"219.667816ms","start":"2026-04-07T00:54:02.784065Z","end":"2026-04-07T00:54:03.003733Z","steps":["trace[1169459942] 'process raft request'  (duration: 219.462361ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:54:03.702227Z","caller":"traceutil/trace.go:171","msg":"trace[650596754] linearizableReadLoop","detail":"{readStateIndex:25498; appliedIndex:25497; }","duration":"181.309725ms","start":"2026-04-07T00:54:03.520892Z","end":"2026-04-07T00:54:03.702201Z","steps":["trace[650596754] 'read index received'  (duration: 181.028219ms)","trace[650596754] 'applied index is now lower than readState.Index'  (duration: 280.576µs)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:54:03.702328Z","caller":"traceutil/trace.go:171","msg":"trace[348314993] transaction","detail":"{read_only:false; response_revision:24689; number_of_response:1; }","duration":"211.503022ms","start":"2026-04-07T00:54:03.490783Z","end":"2026-04-07T00:54:03.702286Z","steps":["trace[348314993] 'process raft request'  (duration: 211.236736ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:54:03.702421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.489528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:54:03.702476Z","caller":"traceutil/trace.go:171","msg":"trace[298080273] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:24689; }","duration":"181.590961ms","start":"2026-04-07T00:54:03.520871Z","end":"2026-04-07T00:54:03.702462Z","steps":["trace[298080273] 'agreement among raft nodes before linearized reading'  (duration: 181.461589ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:54:03.702931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.088665ms","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-04-07T00:54:03.703242Z","caller":"traceutil/trace.go:171","msg":"trace[2141786911] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24689; }","duration":"101.410962ms","start":"2026-04-07T00:54:03.601765Z","end":"2026-04-07T00:54:03.703176Z","steps":["trace[2141786911] 'agreement among raft nodes before linearized reading'  (duration: 100.677867ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:54:04.16247Z","caller":"traceutil/trace.go:171","msg":"trace[1840330566] transaction","detail":"{read_only:false; response_revision:24693; number_of_response:1; }","duration":"104.154432ms","start":"2026-04-07T00:54:04.058287Z","end":"2026-04-07T00:54:04.162441Z","steps":["trace[1840330566] 'process raft request'  (duration: 104.09055ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:54:04.162649Z","caller":"traceutil/trace.go:171","msg":"trace[39216732] transaction","detail":"{read_only:false; response_revision:24692; number_of_response:1; }","duration":"137.518706ms","start":"2026-04-07T00:54:04.025046Z","end":"2026-04-07T00:54:04.162565Z","steps":["trace[39216732] 'process raft request'  (duration: 73.693219ms)","trace[39216732] 'compare'  (duration: 63.062511ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:54:04.346473Z","caller":"traceutil/trace.go:171","msg":"trace[1383140271] transaction","detail":"{read_only:false; response_revision:24694; number_of_response:1; }","duration":"138.321704ms","start":"2026-04-07T00:54:04.208116Z","end":"2026-04-07T00:54:04.346438Z","steps":["trace[1383140271] 'process raft request'  (duration: 70.669685ms)","trace[1383140271] 'compare'  (duration: 67.509446ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:54:04.620701Z","caller":"traceutil/trace.go:171","msg":"trace[1168040954] transaction","detail":"{read_only:false; response_revision:24695; number_of_response:1; }","duration":"112.842118ms","start":"2026-04-07T00:54:04.507815Z","end":"2026-04-07T00:54:04.620658Z","steps":["trace[1168040954] 'process raft request'  (duration: 112.574062ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:54:05.035723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.106471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-07T00:54:05.035856Z","caller":"traceutil/trace.go:171","msg":"trace[577844477] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:24699; }","duration":"139.297755ms","start":"2026-04-07T00:54:04.896521Z","end":"2026-04-07T00:54:05.035819Z","steps":["trace[577844477] 'count revisions from in-memory index tree'  (duration: 138.987219ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:54:05.211145Z","caller":"traceutil/trace.go:171","msg":"trace[1206905204] linearizableReadLoop","detail":"{readStateIndex:25509; appliedIndex:25508; }","duration":"170.256367ms","start":"2026-04-07T00:54:05.04086Z","end":"2026-04-07T00:54:05.211117Z","steps":["trace[1206905204] 'read index received'  (duration: 170.019972ms)","trace[1206905204] 'applied index is now lower than readState.Index'  (duration: 235.535µs)"],"step_count":2}
{"level":"info","ts":"2026-04-07T00:54:05.211532Z","caller":"traceutil/trace.go:171","msg":"trace[684352006] transaction","detail":"{read_only:false; response_revision:24700; number_of_response:1; }","duration":"170.714498ms","start":"2026-04-07T00:54:05.040792Z","end":"2026-04-07T00:54:05.211507Z","steps":["trace[684352006] 'process raft request'  (duration: 170.164005ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T00:54:05.211899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.015594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"}
{"level":"info","ts":"2026-04-07T00:54:05.211953Z","caller":"traceutil/trace.go:171","msg":"trace[732418682] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24700; }","duration":"171.144857ms","start":"2026-04-07T00:54:05.040795Z","end":"2026-04-07T00:54:05.211939Z","steps":["trace[732418682] 'agreement among raft nodes before linearized reading'  (duration: 170.959063ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T00:56:43.697922Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23374}
{"level":"info","ts":"2026-04-07T00:56:43.836173Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23374,"took":"132.56359ms","hash":1960311794,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":36270080,"current-db-size-in-use":"36 MB"}
{"level":"info","ts":"2026-04-07T00:56:43.836255Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1960311794,"revision":23374,"compact-revision":20258}
{"level":"info","ts":"2026-04-07T00:56:48.527706Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a16.wal"}
{"level":"info","ts":"2026-04-07T00:57:56.231905Z","caller":"traceutil/trace.go:171","msg":"trace[625503197] transaction","detail":"{read_only:false; response_revision:27047; number_of_response:1; }","duration":"101.073975ms","start":"2026-04-07T00:57:56.130793Z","end":"2026-04-07T00:57:56.231867Z","steps":["trace[625503197] 'process raft request'  (duration: 100.752858ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T01:01:43.707496Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26266}
{"level":"info","ts":"2026-04-07T01:01:43.859049Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26266,"took":"146.446535ms","hash":2396526516,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":33173504,"current-db-size-in-use":"33 MB"}
{"level":"info","ts":"2026-04-07T01:01:43.859136Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2396526516,"revision":26266,"compact-revision":23374}
{"level":"info","ts":"2026-04-07T01:02:17.400402Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e98569549cc430c","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-04-07T01:02:17.405752Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003}
{"level":"info","ts":"2026-04-07T01:02:17.405933Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}
{"level":"info","ts":"2026-04-07T01:06:43.716168Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28831}
{"level":"info","ts":"2026-04-07T01:06:43.861631Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28831,"took":"140.5394ms","hash":1700021293,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":30441472,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-04-07T01:06:43.861802Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1700021293,"revision":28831,"compact-revision":26266}
{"level":"info","ts":"2026-04-07T01:11:43.726012Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31079}
{"level":"info","ts":"2026-04-07T01:11:43.85058Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31079,"took":"119.669593ms","hash":1254266115,"current-db-size-bytes":56872960,"current-db-size":"57 MB","current-db-size-in-use-bytes":31961088,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-04-07T01:11:43.850651Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1254266115,"revision":31079,"compact-revision":28831}
{"level":"info","ts":"2026-04-07T01:16:43.733749Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33415}
{"level":"info","ts":"2026-04-07T01:16:43.895112Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33415,"took":"154.089128ms","hash":2783917394,"current-db-size-bytes":57917440,"current-db-size":"58 MB","current-db-size-in-use-bytes":32460800,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-04-07T01:16:43.895213Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2783917394,"revision":33415,"compact-revision":31079}
{"level":"info","ts":"2026-04-07T01:21:43.739638Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35641}
{"level":"info","ts":"2026-04-07T01:21:43.879804Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35641,"took":"136.575683ms","hash":1797796595,"current-db-size-bytes":59228160,"current-db-size":"59 MB","current-db-size-in-use-bytes":31375360,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-04-07T01:21:43.879871Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1797796595,"revision":35641,"compact-revision":33415}
{"level":"info","ts":"2026-04-07T01:23:43.373606Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e98569549cc430c","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-04-07T01:23:43.379081Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004}
{"level":"info","ts":"2026-04-07T01:23:43.37921Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}