{"level":"warn","ts":"2026-03-19T17:52:49.84026Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-03-19T17:52:49.840347Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.228: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.19.213.228:2380","--initial-cluster=instance=https://199.19.213.228:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.228:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.228: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-03-19T17:52:49.840417Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-03-19T17:52:49.840429Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.228:2380"]}
{"level":"info","ts":"2026-03-19T17:52:49.840444Z","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-03-19T17:52:49.841555Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"]}
{"level":"info","ts":"2026-03-19T17:52:49.841627Z","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.19.213.228:2380"],"listen-peer-urls":["https://199.19.213.228:2380"],"advertise-client-urls":["https://199.19.213.228:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.228: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-03-19T17:52:49.845296Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.42727ms"}
{"level":"info","ts":"2026-03-19T17:52:49.851426Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"db79120667ce701f","cluster-id":"793c581eb68f3646"}
{"level":"info","ts":"2026-03-19T17:52:49.851492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=()"}
{"level":"info","ts":"2026-03-19T17:52:49.851512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became follower at term 0"}
{"level":"info","ts":"2026-03-19T17:52:49.851527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft db79120667ce701f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-03-19T17:52:49.851534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became follower at term 1"}
{"level":"info","ts":"2026-03-19T17:52:49.851566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=(15814691385208893471)"}
{"level":"warn","ts":"2026-03-19T17:52:49.865122Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-03-19T17:52:49.867097Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-03-19T17:52:49.868116Z","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-03-19T17:52:49.869874Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"db79120667ce701f","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-03-19T17:52:49.870075Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"db79120667ce701f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-03-19T17:52:49.870303Z","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-03-19T17:52:49.870487Z","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-03-19T17:52:49.87052Z","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-03-19T17:52:49.872112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=(15814691385208893471)"}
{"level":"info","ts":"2026-03-19T17:52:49.87291Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"793c581eb68f3646","local-member-id":"db79120667ce701f","added-peer-id":"db79120667ce701f","added-peer-peer-urls":["https://199.19.213.228:2380"]}
{"level":"info","ts":"2026-03-19T17:52:49.876041Z","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-03-19T17:52:49.876227Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.228:2380"}
{"level":"info","ts":"2026-03-19T17:52:49.876289Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.228:2380"}
{"level":"info","ts":"2026-03-19T17:52:49.876415Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"db79120667ce701f","initial-advertise-peer-urls":["https://199.19.213.228:2380"],"listen-peer-urls":["https://199.19.213.228:2380"],"advertise-client-urls":["https://199.19.213.228:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-03-19T17:52:49.876524Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-03-19T17:52:50.652469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f is starting a new election at term 1"}
{"level":"info","ts":"2026-03-19T17:52:50.652568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became pre-candidate at term 1"}
{"level":"info","ts":"2026-03-19T17:52:50.652623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f received MsgPreVoteResp from db79120667ce701f at term 1"}
{"level":"info","ts":"2026-03-19T17:52:50.652646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became candidate at term 2"}
{"level":"info","ts":"2026-03-19T17:52:50.652658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f received MsgVoteResp from db79120667ce701f at term 2"}
{"level":"info","ts":"2026-03-19T17:52:50.652675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became leader at term 2"}
{"level":"info","ts":"2026-03-19T17:52:50.652691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: db79120667ce701f elected leader db79120667ce701f at term 2"}
{"level":"info","ts":"2026-03-19T17:52:50.654247Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"db79120667ce701f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.228:2379]}","request-path":"/0/members/db79120667ce701f/attributes","cluster-id":"793c581eb68f3646","publish-timeout":"7s"}
{"level":"info","ts":"2026-03-19T17:52:50.654298Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-19T17:52:50.654625Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-19T17:52:50.655024Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-19T17:52:50.655243Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-03-19T17:52:50.655358Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-03-19T17:52:50.656179Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"793c581eb68f3646","local-member-id":"db79120667ce701f","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-19T17:52:50.656423Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-19T17:52:50.65647Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-19T17:52:50.659646Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.228:2379"}
{"level":"info","ts":"2026-03-19T17:52:50.659673Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-03-19T17:52:56.085039Z","caller":"traceutil/trace.go:171","msg":"trace[1809563321] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"164.250366ms","start":"2026-03-19T17:52:55.920763Z","end":"2026-03-19T17:52:56.085013Z","steps":["trace[1809563321] 'process raft request'  (duration: 164.099322ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:52:56.087176Z","caller":"traceutil/trace.go:171","msg":"trace[524413486] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"159.047343ms","start":"2026-03-19T17:52:55.928098Z","end":"2026-03-19T17:52:56.087145Z","steps":["trace[524413486] 'process raft request'  (duration: 158.693953ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:53:04.693123Z","caller":"traceutil/trace.go:171","msg":"trace[1315899412] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"106.876674ms","start":"2026-03-19T17:53:04.586207Z","end":"2026-03-19T17:53:04.693083Z","steps":["trace[1315899412] 'process raft request'  (duration: 106.658009ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:53:04.900817Z","caller":"traceutil/trace.go:171","msg":"trace[1450059935] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"199.254783ms","start":"2026-03-19T17:53:04.701531Z","end":"2026-03-19T17:53:04.900785Z","steps":["trace[1450059935] 'process raft request'  (duration: 171.845464ms)","trace[1450059935] 'compare'  (duration: 27.267115ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:53:08.393901Z","caller":"traceutil/trace.go:171","msg":"trace[1251095140] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"230.192582ms","start":"2026-03-19T17:53:08.163682Z","end":"2026-03-19T17:53:08.393874Z","steps":["trace[1251095140] 'read index received'  (duration: 230.020278ms)","trace[1251095140] 'applied index is now lower than readState.Index'  (duration: 171.174µs)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:53:08.393929Z","caller":"traceutil/trace.go:171","msg":"trace[894838990] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"230.483709ms","start":"2026-03-19T17:53:08.163412Z","end":"2026-03-19T17:53:08.393895Z","steps":["trace[894838990] 'process raft request'  (duration: 230.312864ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:53:08.394049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.343276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-03-19T17:53:08.39414Z","caller":"traceutil/trace.go:171","msg":"trace[1256224666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:419; }","duration":"230.523391ms","start":"2026-03-19T17:53:08.163603Z","end":"2026-03-19T17:53:08.394127Z","steps":["trace[1256224666] 'agreement among raft nodes before linearized reading'  (duration: 230.375297ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:53:09.001307Z","caller":"traceutil/trace.go:171","msg":"trace[658104917] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"263.097142ms","start":"2026-03-19T17:53:08.738162Z","end":"2026-03-19T17:53:09.001259Z","steps":["trace[658104917] 'process raft request'  (duration: 262.960069ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:53:29.228164Z","caller":"traceutil/trace.go:171","msg":"trace[484890320] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"123.411571ms","start":"2026-03-19T17:53:29.10472Z","end":"2026-03-19T17:53:29.228132Z","steps":["trace[484890320] 'process raft request'  (duration: 123.213256ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:53:29.713536Z","caller":"traceutil/trace.go:171","msg":"trace[1098866668] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"129.068638ms","start":"2026-03-19T17:53:29.58443Z","end":"2026-03-19T17:53:29.713499Z","steps":["trace[1098866668] 'process raft request'  (duration: 128.824121ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:53:35.858735Z","caller":"traceutil/trace.go:171","msg":"trace[728485669] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"111.130812ms","start":"2026-03-19T17:53:35.747565Z","end":"2026-03-19T17:53:35.858696Z","steps":["trace[728485669] 'process raft request'  (duration: 101.76248ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:53:37.292547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.167051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-03-19T17:53:37.292644Z","caller":"traceutil/trace.go:171","msg":"trace[1124976173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:574; }","duration":"626.317125ms","start":"2026-03-19T17:53:36.666302Z","end":"2026-03-19T17:53:37.292619Z","steps":["trace[1124976173] 'range keys from in-memory index tree'  (duration: 625.989146ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:53:37.29269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:36.666278Z","time spent":"626.402897ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-03-19T17:53:37.293565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.694646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"info","ts":"2026-03-19T17:53:37.293758Z","caller":"traceutil/trace.go:171","msg":"trace[251942006] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"763.976803ms","start":"2026-03-19T17:53:36.52975Z","end":"2026-03-19T17:53:37.293727Z","steps":["trace[251942006] 'range keys from in-memory index tree'  (duration: 762.564387ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:53:37.293848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:36.529729Z","time spent":"764.103707ms","remote":"127.0.0.1:56092","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":"info","ts":"2026-03-19T17:53:38.995561Z","caller":"traceutil/trace.go:171","msg":"trace[1488508613] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"100.210921ms","start":"2026-03-19T17:53:38.895322Z","end":"2026-03-19T17:53:38.995533Z","steps":["trace[1488508613] 'process raft request'  (duration: 100.042287ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:53:49.518547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.370307ms","expected-duration":"100ms","prefix":"","request":"header:<ID:8079348910898332123 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:800 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-19T17:53:49.518696Z","caller":"traceutil/trace.go:171","msg":"trace[871873994] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"165.069438ms","start":"2026-03-19T17:53:49.353591Z","end":"2026-03-19T17:53:49.518661Z","steps":["trace[871873994] 'process raft request'  (duration: 61.911911ms)","trace[871873994] 'compare'  (duration: 102.222004ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:53:49.518731Z","caller":"traceutil/trace.go:171","msg":"trace[1326693011] linearizableReadLoop","detail":"{readStateIndex:875; appliedIndex:873; }","duration":"162.602034ms","start":"2026-03-19T17:53:49.356058Z","end":"2026-03-19T17:53:49.51866Z","steps":["trace[1326693011] 'read index received'  (duration: 5.309367ms)","trace[1326693011] 'applied index is now lower than readState.Index'  (duration: 157.290767ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T17:53:49.518947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.861781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-03-19T17:53:49.519096Z","caller":"traceutil/trace.go:171","msg":"trace[1302626831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:847; }","duration":"163.027985ms","start":"2026-03-19T17:53:49.356036Z","end":"2026-03-19T17:53:49.519064Z","steps":["trace[1302626831] 'agreement among raft nodes before linearized reading'  (duration: 162.709696ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:53:49.520851Z","caller":"traceutil/trace.go:171","msg":"trace[961435505] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"124.786486ms","start":"2026-03-19T17:53:49.396043Z","end":"2026-03-19T17:53:49.52083Z","steps":["trace[961435505] 'process raft request'  (duration: 124.689324ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:53:49.520858Z","caller":"traceutil/trace.go:171","msg":"trace[1716960701] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"156.273691ms","start":"2026-03-19T17:53:49.364529Z","end":"2026-03-19T17:53:49.520803Z","steps":["trace[1716960701] 'process raft request'  (duration: 156.100347ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:53:57.418176Z","caller":"traceutil/trace.go:171","msg":"trace[2068786106] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"430.387984ms","start":"2026-03-19T17:53:56.987769Z","end":"2026-03-19T17:53:57.418157Z","steps":["trace[2068786106] 'process raft request'  (duration: 430.201289ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:53:57.418296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:56.987752Z","time spent":"430.485548ms","remote":"127.0.0.1:56268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4734,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/deployments/cert-manager/cert-manager\" mod_revision:731 > success:<request_put:<key:\"/registry/deployments/cert-manager/cert-manager\" value_size:4679 >> failure:<request_range:<key:\"/registry/deployments/cert-manager/cert-manager\" > >"}
{"level":"warn","ts":"2026-03-19T17:54:18.20311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.951126ms","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-03-19T17:54:18.20321Z","caller":"traceutil/trace.go:171","msg":"trace[426255262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1099; }","duration":"204.102269ms","start":"2026-03-19T17:54:17.999085Z","end":"2026-03-19T17:54:18.203187Z","steps":["trace[426255262] 'range keys from in-memory index tree'  (duration: 203.7395ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:54:26.487422Z","caller":"traceutil/trace.go:171","msg":"trace[1079169489] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"201.808089ms","start":"2026-03-19T17:54:26.285577Z","end":"2026-03-19T17:54:26.487385Z","steps":["trace[1079169489] 'process raft request'  (duration: 201.653365ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:54:42.71934Z","caller":"traceutil/trace.go:171","msg":"trace[1037871535] linearizableReadLoop","detail":"{readStateIndex:1539; appliedIndex:1538; }","duration":"121.584647ms","start":"2026-03-19T17:54:42.597736Z","end":"2026-03-19T17:54:42.71932Z","steps":["trace[1037871535] 'read index received'  (duration: 121.497065ms)","trace[1037871535] 'applied index is now lower than readState.Index'  (duration: 86.632µs)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:54:42.719406Z","caller":"traceutil/trace.go:171","msg":"trace[1591328168] transaction","detail":"{read_only:false; response_revision:1490; number_of_response:1; }","duration":"137.017938ms","start":"2026-03-19T17:54:42.582344Z","end":"2026-03-19T17:54:42.719362Z","steps":["trace[1591328168] 'process raft request'  (duration: 136.880255ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:54:42.719695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.934535ms","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-03-19T17:54:42.719854Z","caller":"traceutil/trace.go:171","msg":"trace[775034119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1490; }","duration":"122.136191ms","start":"2026-03-19T17:54:42.597695Z","end":"2026-03-19T17:54:42.719831Z","steps":["trace[775034119] 'agreement among raft nodes before linearized reading'  (duration: 121.70595ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:54:42.860856Z","caller":"traceutil/trace.go:171","msg":"trace[89932992] transaction","detail":"{read_only:false; response_revision:1491; number_of_response:1; }","duration":"137.498651ms","start":"2026-03-19T17:54:42.723331Z","end":"2026-03-19T17:54:42.86083Z","steps":["trace[89932992] 'process raft request'  (duration: 58.175841ms)","trace[89932992] 'compare'  (duration: 79.124985ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:54:42.86123Z","caller":"traceutil/trace.go:171","msg":"trace[597596902] transaction","detail":"{read_only:false; response_revision:1492; number_of_response:1; }","duration":"133.841755ms","start":"2026-03-19T17:54:42.727357Z","end":"2026-03-19T17:54:42.861199Z","steps":["trace[597596902] 'process raft request'  (duration: 133.424955ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:54:43.788834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.506585ms","expected-duration":"100ms","prefix":"","request":"header:<ID:8079348910898333399 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/endpointslices/ingress-nginx/ingress-nginx-controller-metrics-r2lkl\" mod_revision:1176 > success:<request_put:<key:\"/registry/endpointslices/ingress-nginx/ingress-nginx-controller-metrics-r2lkl\" value_size:1649 >> failure:<request_range:<key:\"/registry/endpointslices/ingress-nginx/ingress-nginx-controller-metrics-r2lkl\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-19T17:54:43.788937Z","caller":"traceutil/trace.go:171","msg":"trace[2045316007] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"205.182878ms","start":"2026-03-19T17:54:43.583741Z","end":"2026-03-19T17:54:43.788924Z","steps":["trace[2045316007] 'process raft request'  (duration: 98.242032ms)","trace[2045316007] 'compare'  (duration: 106.412433ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:54:43.81783Z","caller":"traceutil/trace.go:171","msg":"trace[1985568842] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"233.966734ms","start":"2026-03-19T17:54:43.583844Z","end":"2026-03-19T17:54:43.817811Z","steps":["trace[1985568842] 'process raft request'  (duration: 233.824451ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:54:43.818124Z","caller":"traceutil/trace.go:171","msg":"trace[920548546] transaction","detail":"{read_only:false; response_revision:1499; number_of_response:1; }","duration":"233.229555ms","start":"2026-03-19T17:54:43.584859Z","end":"2026-03-19T17:54:43.818089Z","steps":["trace[920548546] 'process raft request'  (duration: 232.910737ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:54:43.818219Z","caller":"traceutil/trace.go:171","msg":"trace[616665436] transaction","detail":"{read_only:false; response_revision:1500; number_of_response:1; }","duration":"233.093372ms","start":"2026-03-19T17:54:43.585096Z","end":"2026-03-19T17:54:43.81819Z","steps":["trace[616665436] 'process raft request'  (duration: 232.897417ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:54:43.818394Z","caller":"traceutil/trace.go:171","msg":"trace[547045425] transaction","detail":"{read_only:false; response_revision:1501; number_of_response:1; }","duration":"233.095052ms","start":"2026-03-19T17:54:43.585277Z","end":"2026-03-19T17:54:43.818373Z","steps":["trace[547045425] 'process raft request'  (duration: 232.847695ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:54:43.818511Z","caller":"traceutil/trace.go:171","msg":"trace[1841840921] transaction","detail":"{read_only:false; response_revision:1502; number_of_response:1; }","duration":"233.211455ms","start":"2026-03-19T17:54:43.585289Z","end":"2026-03-19T17:54:43.818501Z","steps":["trace[1841840921] 'process raft request'  (duration: 232.993699ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:54:43.818527Z","caller":"traceutil/trace.go:171","msg":"trace[222677364] linearizableReadLoop","detail":"{readStateIndex:1552; appliedIndex:1545; }","duration":"139.000719ms","start":"2026-03-19T17:54:43.679506Z","end":"2026-03-19T17:54:43.818507Z","steps":["trace[222677364] 'read index received'  (duration: 2.466695ms)","trace[222677364] 'applied index is now lower than readState.Index'  (duration: 136.533194ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:54:43.818579Z","caller":"traceutil/trace.go:171","msg":"trace[541185669] transaction","detail":"{read_only:false; response_revision:1503; number_of_response:1; }","duration":"231.540462ms","start":"2026-03-19T17:54:43.587026Z","end":"2026-03-19T17:54:43.818566Z","steps":["trace[541185669] 'process raft request'  (duration: 231.418319ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:54:43.818683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.167803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"info","ts":"2026-03-19T17:54:43.818718Z","caller":"traceutil/trace.go:171","msg":"trace[881966632] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1503; }","duration":"139.243885ms","start":"2026-03-19T17:54:43.679464Z","end":"2026-03-19T17:54:43.818708Z","steps":["trace[881966632] 'agreement among raft nodes before linearized reading'  (duration: 139.116772ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:54:48.052258Z","caller":"traceutil/trace.go:171","msg":"trace[155781084] linearizableReadLoop","detail":"{readStateIndex:1650; appliedIndex:1649; }","duration":"220.504825ms","start":"2026-03-19T17:54:47.83173Z","end":"2026-03-19T17:54:48.052235Z","steps":["trace[155781084] 'read index received'  (duration: 220.29422ms)","trace[155781084] 'applied index is now lower than readState.Index'  (duration: 209.795µs)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:54:48.052316Z","caller":"traceutil/trace.go:171","msg":"trace[1885352674] transaction","detail":"{read_only:false; response_revision:1599; number_of_response:1; }","duration":"243.210775ms","start":"2026-03-19T17:54:47.809075Z","end":"2026-03-19T17:54:48.052286Z","steps":["trace[1885352674] 'process raft request'  (duration: 243.01502ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:54:48.052424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.673269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-19T17:54:48.052463Z","caller":"traceutil/trace.go:171","msg":"trace[1145859526] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1599; }","duration":"220.776192ms","start":"2026-03-19T17:54:47.831675Z","end":"2026-03-19T17:54:48.052452Z","steps":["trace[1145859526] 'agreement among raft nodes before linearized reading'  (duration: 220.69065ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:54:48.052621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.143329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"info","ts":"2026-03-19T17:54:48.052706Z","caller":"traceutil/trace.go:171","msg":"trace[531664867] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1599; }","duration":"177.254122ms","start":"2026-03-19T17:54:47.875432Z","end":"2026-03-19T17:54:48.052687Z","steps":["trace[531664867] 'agreement among raft nodes before linearized reading'  (duration: 177.039246ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:54:48.281885Z","caller":"traceutil/trace.go:171","msg":"trace[277618572] transaction","detail":"{read_only:false; response_revision:1600; number_of_response:1; }","duration":"224.618512ms","start":"2026-03-19T17:54:48.057235Z","end":"2026-03-19T17:54:48.281853Z","steps":["trace[277618572] 'process raft request'  (duration: 150.938029ms)","trace[277618572] 'compare'  (duration: 73.456227ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:54:48.282341Z","caller":"traceutil/trace.go:171","msg":"trace[1458642823] transaction","detail":"{read_only:false; response_revision:1601; number_of_response:1; }","duration":"224.994832ms","start":"2026-03-19T17:54:48.05732Z","end":"2026-03-19T17:54:48.282315Z","steps":["trace[1458642823] 'process raft request'  (duration: 224.471208ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:54:48.282643Z","caller":"traceutil/trace.go:171","msg":"trace[862536742] linearizableReadLoop","detail":"{readStateIndex:1653; appliedIndex:1650; }","duration":"151.680548ms","start":"2026-03-19T17:54:48.130917Z","end":"2026-03-19T17:54:48.282597Z","steps":["trace[862536742] 'read index received'  (duration: 77.277007ms)","trace[862536742] 'applied index is now lower than readState.Index'  (duration: 74.401181ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:54:48.282722Z","caller":"traceutil/trace.go:171","msg":"trace[1929052553] transaction","detail":"{read_only:false; response_revision:1602; number_of_response:1; }","duration":"224.623413ms","start":"2026-03-19T17:54:48.058072Z","end":"2026-03-19T17:54:48.282696Z","steps":["trace[1929052553] 'process raft request'  (duration: 224.11198ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:54:48.282928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.002998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ec9893be-8d05-4041-a17d-68efd7227a10\" ","response":"range_response_count:1 size:3627"}
{"level":"info","ts":"2026-03-19T17:54:48.283006Z","caller":"traceutil/trace.go:171","msg":"trace[559937676] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ec9893be-8d05-4041-a17d-68efd7227a10; range_end:; response_count:1; response_revision:1602; }","duration":"152.12027ms","start":"2026-03-19T17:54:48.130849Z","end":"2026-03-19T17:54:48.28297Z","steps":["trace[559937676] 'agreement among raft nodes before linearized reading'  (duration: 151.937075ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:54:56.800155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.157407ms","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-03-19T17:54:56.800204Z","caller":"traceutil/trace.go:171","msg":"trace[2147237960] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1693; }","duration":"180.25892ms","start":"2026-03-19T17:54:56.619935Z","end":"2026-03-19T17:54:56.800194Z","steps":["trace[2147237960] 'count revisions from in-memory index tree'  (duration: 180.069545ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:54:57.416702Z","caller":"traceutil/trace.go:171","msg":"trace[1231381660] linearizableReadLoop","detail":"{readStateIndex:1751; appliedIndex:1750; }","duration":"459.698056ms","start":"2026-03-19T17:54:56.956979Z","end":"2026-03-19T17:54:57.416677Z","steps":["trace[1231381660] 'read index received'  (duration: 459.571913ms)","trace[1231381660] 'applied index is now lower than readState.Index'  (duration: 124.513µs)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:54:57.416763Z","caller":"traceutil/trace.go:171","msg":"trace[837217393] transaction","detail":"{read_only:false; response_revision:1694; number_of_response:1; }","duration":"612.668287ms","start":"2026-03-19T17:54:56.804061Z","end":"2026-03-19T17:54:57.41673Z","steps":["trace[837217393] 'process raft request'  (duration: 612.432831ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:54:57.416912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:56.804051Z","time spent":"612.78544ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/cert-manager/cert-manager-controller\" mod_revision:1486 > success:<request_put:<key:\"/registry/leases/cert-manager/cert-manager-controller\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/cert-manager/cert-manager-controller\" > >"}
{"level":"warn","ts":"2026-03-19T17:54:57.416937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.942862ms","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-03-19T17:54:57.416974Z","caller":"traceutil/trace.go:171","msg":"trace[777742346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1694; }","duration":"460.034634ms","start":"2026-03-19T17:54:56.956928Z","end":"2026-03-19T17:54:57.416962Z","steps":["trace[777742346] 'agreement among raft nodes before linearized reading'  (duration: 459.87901ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:54:57.417027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:56.956907Z","time spent":"460.111517ms","remote":"127.0.0.1:56092","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-03-19T17:54:57.41718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.310136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"}
{"level":"info","ts":"2026-03-19T17:54:57.417246Z","caller":"traceutil/trace.go:171","msg":"trace[333847071] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1694; }","duration":"147.407347ms","start":"2026-03-19T17:54:57.26982Z","end":"2026-03-19T17:54:57.417228Z","steps":["trace[333847071] 'agreement among raft nodes before linearized reading'  (duration: 147.240363ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:54:57.417253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.226846ms","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-03-19T17:54:57.417262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.064843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"}
{"level":"info","ts":"2026-03-19T17:54:57.417303Z","caller":"traceutil/trace.go:171","msg":"trace[1280362132] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1694; }","duration":"327.327209ms","start":"2026-03-19T17:54:57.089961Z","end":"2026-03-19T17:54:57.417288Z","steps":["trace[1280362132] 'agreement among raft nodes before linearized reading'  (duration: 327.181135ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:54:57.417291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.540317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"}
{"level":"warn","ts":"2026-03-19T17:54:57.417355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:57.089939Z","time spent":"327.399351ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"info","ts":"2026-03-19T17:54:57.417354Z","caller":"traceutil/trace.go:171","msg":"trace[962902690] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1694; }","duration":"381.193568ms","start":"2026-03-19T17:54:57.036132Z","end":"2026-03-19T17:54:57.417326Z","steps":["trace[962902690] 'agreement among raft nodes before linearized reading'  (duration: 380.920741ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:54:57.417384Z","caller":"traceutil/trace.go:171","msg":"trace[530811404] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1694; }","duration":"304.688491ms","start":"2026-03-19T17:54:57.112673Z","end":"2026-03-19T17:54:57.417361Z","steps":["trace[530811404] 'agreement among raft nodes before linearized reading'  (duration: 304.396964ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:54:57.417413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:57.036112Z","time spent":"381.2882ms","remote":"127.0.0.1:55938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":682,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "}
{"level":"warn","ts":"2026-03-19T17:54:57.417441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:57.112649Z","time spent":"304.781133ms","remote":"127.0.0.1:45248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4614,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "}
{"level":"info","ts":"2026-03-19T17:54:59.566785Z","caller":"traceutil/trace.go:171","msg":"trace[1277932722] transaction","detail":"{read_only:false; response_revision:1711; number_of_response:1; }","duration":"115.507099ms","start":"2026-03-19T17:54:59.451242Z","end":"2026-03-19T17:54:59.566749Z","steps":["trace[1277932722] 'process raft request'  (duration: 115.323695ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:55:21.649302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.743342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-03-19T17:55:21.649401Z","caller":"traceutil/trace.go:171","msg":"trace[822143425] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1856; }","duration":"109.891187ms","start":"2026-03-19T17:55:21.539488Z","end":"2026-03-19T17:55:21.64938Z","steps":["trace[822143425] 'range keys from in-memory index tree'  (duration: 109.589968ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:55:21.649417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.126667ms","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-03-19T17:55:21.649483Z","caller":"traceutil/trace.go:171","msg":"trace[755750071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1856; }","duration":"109.22301ms","start":"2026-03-19T17:55:21.540237Z","end":"2026-03-19T17:55:21.64946Z","steps":["trace[755750071] 'range keys from in-memory index tree'  (duration: 108.924531ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:55:22.833598Z","caller":"traceutil/trace.go:171","msg":"trace[1267685221] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"137.101374ms","start":"2026-03-19T17:55:22.696461Z","end":"2026-03-19T17:55:22.833562Z","steps":["trace[1267685221] 'process raft request'  (duration: 135.7846ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:55:31.733826Z","caller":"traceutil/trace.go:171","msg":"trace[515562218] linearizableReadLoop","detail":"{readStateIndex:1990; appliedIndex:1989; }","duration":"179.989169ms","start":"2026-03-19T17:55:31.55382Z","end":"2026-03-19T17:55:31.733809Z","steps":["trace[515562218] 'read index received'  (duration: 179.840735ms)","trace[515562218] 'applied index is now lower than readState.Index'  (duration: 147.614µs)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:55:31.73386Z","caller":"traceutil/trace.go:171","msg":"trace[618902838] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"296.356525ms","start":"2026-03-19T17:55:31.437481Z","end":"2026-03-19T17:55:31.733838Z","steps":["trace[618902838] 'process raft request'  (duration: 296.202901ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:55:31.733945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.112052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-19T17:55:31.733962Z","caller":"traceutil/trace.go:171","msg":"trace[286928437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1920; }","duration":"180.168964ms","start":"2026-03-19T17:55:31.553788Z","end":"2026-03-19T17:55:31.733957Z","steps":["trace[286928437] 'agreement among raft nodes before linearized reading'  (duration: 180.109282ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:55:32.118181Z","caller":"traceutil/trace.go:171","msg":"trace[1814542588] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"380.865191ms","start":"2026-03-19T17:55:31.737283Z","end":"2026-03-19T17:55:32.118148Z","steps":["trace[1814542588] 'process raft request'  (duration: 286.676572ms)","trace[1814542588] 'compare'  (duration: 94.010594ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T17:55:32.118381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:31.737268Z","time spent":"381.022244ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:1910 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-03-19T17:55:32.122236Z","caller":"traceutil/trace.go:171","msg":"trace[1688790002] linearizableReadLoop","detail":"{readStateIndex:1992; appliedIndex:1990; }","duration":"369.844225ms","start":"2026-03-19T17:55:31.752356Z","end":"2026-03-19T17:55:32.1222Z","steps":["trace[1688790002] 'read index received'  (duration: 271.615371ms)","trace[1688790002] 'applied index is now lower than readState.Index'  (duration: 98.227834ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:55:32.122347Z","caller":"traceutil/trace.go:171","msg":"trace[721940364] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"384.571988ms","start":"2026-03-19T17:55:31.737695Z","end":"2026-03-19T17:55:32.122267Z","steps":["trace[721940364] 'process raft request'  (duration: 384.374103ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:55:32.122613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:31.737672Z","time spent":"384.803823ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:1909 > 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-03-19T17:55:32.122602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.991648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"}
{"level":"warn","ts":"2026-03-19T17:55:32.122602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.398453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"}
{"level":"warn","ts":"2026-03-19T17:55:32.122646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.909976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"}
{"level":"info","ts":"2026-03-19T17:55:32.122711Z","caller":"traceutil/trace.go:171","msg":"trace[1484863927] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1922; }","duration":"369.995717ms","start":"2026-03-19T17:55:31.752692Z","end":"2026-03-19T17:55:32.122688Z","steps":["trace[1484863927] 'agreement among raft nodes before linearized reading'  (duration: 369.72087ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:55:32.122701Z","caller":"traceutil/trace.go:171","msg":"trace[2146551741] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1922; }","duration":"260.122751ms","start":"2026-03-19T17:55:31.862544Z","end":"2026-03-19T17:55:32.122667Z","steps":["trace[2146551741] 'agreement among raft nodes before linearized reading'  (duration: 259.962217ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:55:32.122602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.218184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"}
{"level":"warn","ts":"2026-03-19T17:55:32.122767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:31.752682Z","time spent":"370.0723ms","remote":"127.0.0.1:45248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4614,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "}
{"level":"info","ts":"2026-03-19T17:55:32.122772Z","caller":"traceutil/trace.go:171","msg":"trace[1736721337] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:1922; }","duration":"370.430489ms","start":"2026-03-19T17:55:31.75233Z","end":"2026-03-19T17:55:32.122761Z","steps":["trace[1736721337] 'agreement among raft nodes before linearized reading'  (duration: 370.04467ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:55:32.12272Z","caller":"traceutil/trace.go:171","msg":"trace[130622447] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1922; }","duration":"229.546546ms","start":"2026-03-19T17:55:31.893147Z","end":"2026-03-19T17:55:32.122693Z","steps":["trace[130622447] 'agreement among raft nodes before linearized reading'  (duration: 229.334861ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:55:32.122845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:31.752319Z","time spent":"370.510803ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "}
{"level":"warn","ts":"2026-03-19T17:55:32.96539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.868286ms","expected-duration":"100ms","prefix":"","request":"header:<ID:8079348910898334411 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/statefulsets/openstack/percona-xtradb-pxc\" mod_revision:1895 > success:<request_put:<key:\"/registry/statefulsets/openstack/percona-xtradb-pxc\" value_size:16324 >> failure:<request_range:<key:\"/registry/statefulsets/openstack/percona-xtradb-pxc\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-19T17:55:32.965494Z","caller":"traceutil/trace.go:171","msg":"trace[402935146] linearizableReadLoop","detail":"{readStateIndex:1998; appliedIndex:1997; }","duration":"411.408294ms","start":"2026-03-19T17:55:32.554069Z","end":"2026-03-19T17:55:32.965478Z","steps":["trace[402935146] 'read index received'  (duration: 60.502µs)","trace[402935146] 'applied index is now lower than readState.Index'  (duration: 411.346752ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T17:55:32.965589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.525996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-19T17:55:32.965622Z","caller":"traceutil/trace.go:171","msg":"trace[1617391166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1928; }","duration":"411.593439ms","start":"2026-03-19T17:55:32.554018Z","end":"2026-03-19T17:55:32.965611Z","steps":["trace[1617391166] 'agreement among raft nodes before linearized reading'  (duration: 411.542948ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:55:32.965652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:32.553997Z","time spent":"411.648101ms","remote":"127.0.0.1:55836","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-03-19T17:55:32.965667Z","caller":"traceutil/trace.go:171","msg":"trace[1578814186] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"648.782144ms","start":"2026-03-19T17:55:32.316786Z","end":"2026-03-19T17:55:32.965568Z","steps":["trace[1578814186] 'process raft request'  (duration: 111.661882ms)","trace[1578814186] 'compare'  (duration: 536.767613ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T17:55:32.965772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.554234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"warn","ts":"2026-03-19T17:55:32.965844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:32.316756Z","time spent":"648.99256ms","remote":"127.0.0.1:56284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16383,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/statefulsets/openstack/percona-xtradb-pxc\" mod_revision:1895 > success:<request_put:<key:\"/registry/statefulsets/openstack/percona-xtradb-pxc\" value_size:16324 >> failure:<request_range:<key:\"/registry/statefulsets/openstack/percona-xtradb-pxc\" > >"}
{"level":"info","ts":"2026-03-19T17:55:32.965856Z","caller":"traceutil/trace.go:171","msg":"trace[1525720287] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1928; }","duration":"392.665148ms","start":"2026-03-19T17:55:32.573169Z","end":"2026-03-19T17:55:32.965834Z","steps":["trace[1525720287] 'agreement among raft nodes before linearized reading'  (duration: 392.460822ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:55:32.965897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:32.573155Z","time spent":"392.732449ms","remote":"127.0.0.1:56092","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-03-19T17:55:32.96634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.43675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-03-19T17:55:32.966386Z","caller":"traceutil/trace.go:171","msg":"trace[478842203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1928; }","duration":"185.522323ms","start":"2026-03-19T17:55:32.780852Z","end":"2026-03-19T17:55:32.966374Z","steps":["trace[478842203] 'agreement among raft nodes before linearized reading'  (duration: 185.190865ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:55:33.759445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.260145ms","expected-duration":"100ms","prefix":"","request":"header:<ID:8079348910898334415 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:1918 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-19T17:55:33.759647Z","caller":"traceutil/trace.go:171","msg":"trace[243277831] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"789.302808ms","start":"2026-03-19T17:55:32.970323Z","end":"2026-03-19T17:55:33.759626Z","steps":["trace[243277831] 'process raft request'  (duration: 326.799135ms)","trace[243277831] 'compare'  (duration: 461.990989ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T17:55:33.759813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:32.970307Z","time spent":"789.437732ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:1918 > 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-03-19T17:55:33.792649Z","caller":"traceutil/trace.go:171","msg":"trace[1699601704] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"822.196413ms","start":"2026-03-19T17:55:32.970428Z","end":"2026-03-19T17:55:33.792624Z","steps":["trace[1699601704] 'process raft request'  (duration: 822.012648ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:55:33.792806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:32.970404Z","time spent":"822.311036ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:1917 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-03-19T17:55:33.792956Z","caller":"traceutil/trace.go:171","msg":"trace[1657880502] linearizableReadLoop","detail":"{readStateIndex:2002; appliedIndex:1998; }","duration":"662.076211ms","start":"2026-03-19T17:55:33.130794Z","end":"2026-03-19T17:55:33.79287Z","steps":["trace[1657880502] 'read index received'  (duration: 166.333574ms)","trace[1657880502] 'applied index is now lower than readState.Index'  (duration: 495.741527ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:55:33.79304Z","caller":"traceutil/trace.go:171","msg":"trace[567315665] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1931; }","duration":"817.845959ms","start":"2026-03-19T17:55:32.975081Z","end":"2026-03-19T17:55:33.792927Z","steps":["trace[567315665] 'process raft request'  (duration: 817.708716ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:55:33.793163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.242044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-03-19T17:55:33.793201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:32.975073Z","time spent":"818.062565ms","remote":"127.0.0.1:56284","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare:<target:MOD key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" mod_revision:0 > success:<request_put:<key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" value_size:7315 >> failure:<>"}
{"level":"info","ts":"2026-03-19T17:55:33.793044Z","caller":"traceutil/trace.go:171","msg":"trace[1289795025] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"821.420772ms","start":"2026-03-19T17:55:32.971561Z","end":"2026-03-19T17:55:33.792982Z","steps":["trace[1289795025] 'process raft request'  (duration: 821.007152ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:55:33.793213Z","caller":"traceutil/trace.go:171","msg":"trace[686918052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1931; }","duration":"225.344388ms","start":"2026-03-19T17:55:33.567856Z","end":"2026-03-19T17:55:33.7932Z","steps":["trace[686918052] 'agreement among raft nodes before linearized reading'  (duration: 225.226704ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:55:33.793256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.43763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"}
{"level":"warn","ts":"2026-03-19T17:55:33.793334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:32.971547Z","time spent":"821.71142ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:1919 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-03-19T17:55:33.793344Z","caller":"traceutil/trace.go:171","msg":"trace[28487124] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1931; }","duration":"662.587904ms","start":"2026-03-19T17:55:33.130734Z","end":"2026-03-19T17:55:33.793322Z","steps":["trace[28487124] 'agreement among raft nodes before linearized reading'  (duration: 662.307196ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:55:33.793427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:33.130711Z","time spent":"662.705887ms","remote":"127.0.0.1:45248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4614,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "}
{"level":"info","ts":"2026-03-19T17:56:27.448141Z","caller":"traceutil/trace.go:171","msg":"trace[1896028699] transaction","detail":"{read_only:false; number_of_response:0; response_revision:2330; }","duration":"105.081426ms","start":"2026-03-19T17:56:27.343034Z","end":"2026-03-19T17:56:27.448115Z","steps":["trace[1896028699] 'process raft request'  (duration: 104.963923ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:56:27.778421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.872735ms","expected-duration":"100ms","prefix":"","request":"header:<ID:8079348910898335512 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/cert-manager/cert-manager-controller\" mod_revision:2231 > success:<request_put:<key:\"/registry/leases/cert-manager/cert-manager-controller\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/cert-manager/cert-manager-controller\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-19T17:56:27.778529Z","caller":"traceutil/trace.go:171","msg":"trace[1928125725] linearizableReadLoop","detail":"{readStateIndex:2427; appliedIndex:2426; }","duration":"195.693895ms","start":"2026-03-19T17:56:27.582819Z","end":"2026-03-19T17:56:27.778513Z","steps":["trace[1928125725] 'read index received'  (duration: 70.477662ms)","trace[1928125725] 'applied index is now lower than readState.Index'  (duration: 125.214963ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:56:27.778567Z","caller":"traceutil/trace.go:171","msg":"trace[693081557] transaction","detail":"{read_only:false; response_revision:2332; number_of_response:1; }","duration":"214.020994ms","start":"2026-03-19T17:56:27.564504Z","end":"2026-03-19T17:56:27.778525Z","steps":["trace[693081557] 'process raft request'  (duration: 88.826712ms)","trace[693081557] 'compare'  (duration: 124.31736ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T17:56:27.778659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.848466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-19T17:56:27.778702Z","caller":"traceutil/trace.go:171","msg":"trace[284139646] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:2332; }","duration":"105.933119ms","start":"2026-03-19T17:56:27.67276Z","end":"2026-03-19T17:56:27.778693Z","steps":["trace[284139646] 'agreement among raft nodes before linearized reading'  (duration: 105.863947ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:56:27.778714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.891351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"}
{"level":"info","ts":"2026-03-19T17:56:27.778753Z","caller":"traceutil/trace.go:171","msg":"trace[1614664819] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2332; }","duration":"195.965982ms","start":"2026-03-19T17:56:27.582777Z","end":"2026-03-19T17:56:27.778743Z","steps":["trace[1614664819] 'agreement among raft nodes before linearized reading'  (duration: 195.822258ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:56:28.033166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.631156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-03-19T17:56:28.033252Z","caller":"traceutil/trace.go:171","msg":"trace[1055328693] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2332; }","duration":"135.80167ms","start":"2026-03-19T17:56:27.897434Z","end":"2026-03-19T17:56:28.033236Z","steps":["trace[1055328693] 'count revisions from in-memory index tree'  (duration: 135.40896ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:56:28.233917Z","caller":"traceutil/trace.go:171","msg":"trace[1137081467] transaction","detail":"{read_only:false; response_revision:2334; number_of_response:1; }","duration":"196.202588ms","start":"2026-03-19T17:56:28.037694Z","end":"2026-03-19T17:56:28.233897Z","steps":["trace[1137081467] 'process raft request'  (duration: 142.67689ms)","trace[1137081467] 'compare'  (duration: 53.399345ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:56:28.234173Z","caller":"traceutil/trace.go:171","msg":"trace[895096244] transaction","detail":"{read_only:false; response_revision:2335; number_of_response:1; }","duration":"196.107175ms","start":"2026-03-19T17:56:28.038035Z","end":"2026-03-19T17:56:28.234142Z","steps":["trace[895096244] 'process raft request'  (duration: 195.827538ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:56:45.69071Z","caller":"traceutil/trace.go:171","msg":"trace[2063649521] linearizableReadLoop","detail":"{readStateIndex:2545; appliedIndex:2544; }","duration":"135.801309ms","start":"2026-03-19T17:56:45.554882Z","end":"2026-03-19T17:56:45.690683Z","steps":["trace[2063649521] 'read index received'  (duration: 135.537523ms)","trace[2063649521] 'applied index is now lower than readState.Index'  (duration: 262.336µs)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:56:45.69077Z","caller":"traceutil/trace.go:171","msg":"trace[2073162447] transaction","detail":"{read_only:false; response_revision:2443; number_of_response:1; }","duration":"173.411652ms","start":"2026-03-19T17:56:45.517326Z","end":"2026-03-19T17:56:45.690737Z","steps":["trace[2073162447] 'process raft request'  (duration: 173.119405ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:56:45.690863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.972384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-19T17:56:45.690903Z","caller":"traceutil/trace.go:171","msg":"trace[1906244275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2443; }","duration":"136.079268ms","start":"2026-03-19T17:56:45.554814Z","end":"2026-03-19T17:56:45.690893Z","steps":["trace[1906244275] 'agreement among raft nodes before linearized reading'  (duration: 135.992086ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:57:13.789912Z","caller":"traceutil/trace.go:171","msg":"trace[1783288219] transaction","detail":"{read_only:false; response_revision:2721; number_of_response:1; }","duration":"185.597167ms","start":"2026-03-19T17:57:13.604291Z","end":"2026-03-19T17:57:13.789889Z","steps":["trace[1783288219] 'process raft request'  (duration: 183.837481ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:57:13.79756Z","caller":"traceutil/trace.go:171","msg":"trace[1374839905] transaction","detail":"{read_only:false; response_revision:2722; number_of_response:1; }","duration":"190.918126ms","start":"2026-03-19T17:57:13.606628Z","end":"2026-03-19T17:57:13.797546Z","steps":["trace[1374839905] 'process raft request'  (duration: 190.815633ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:57:42.884436Z","caller":"traceutil/trace.go:171","msg":"trace[561442704] linearizableReadLoop","detail":"{readStateIndex:3095; appliedIndex:3094; }","duration":"394.567583ms","start":"2026-03-19T17:57:42.489847Z","end":"2026-03-19T17:57:42.884415Z","steps":["trace[561442704] 'read index received'  (duration: 394.385757ms)","trace[561442704] 'applied index is now lower than readState.Index'  (duration: 180.786µs)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:57:42.884564Z","caller":"traceutil/trace.go:171","msg":"trace[1345979139] transaction","detail":"{read_only:false; response_revision:2967; number_of_response:1; }","duration":"431.126421ms","start":"2026-03-19T17:57:42.453383Z","end":"2026-03-19T17:57:42.88451Z","steps":["trace[1345979139] 'process raft request'  (duration: 430.900865ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:57:42.884737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:57:42.453348Z","time spent":"431.302066ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:2956 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-03-19T17:57:42.884738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.809789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-19T17:57:42.884987Z","caller":"traceutil/trace.go:171","msg":"trace[1613625181] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:2967; }","duration":"395.161457ms","start":"2026-03-19T17:57:42.489794Z","end":"2026-03-19T17:57:42.884955Z","steps":["trace[1613625181] 'agreement among raft nodes before linearized reading'  (duration: 394.758467ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:57:42.8851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.312ms","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-03-19T17:57:42.885167Z","caller":"traceutil/trace.go:171","msg":"trace[730061479] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2967; }","duration":"284.430894ms","start":"2026-03-19T17:57:42.600723Z","end":"2026-03-19T17:57:42.885154Z","steps":["trace[730061479] 'agreement among raft nodes before linearized reading'  (duration: 284.321591ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:57:42.88514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:57:42.48977Z","time spent":"395.348043ms","remote":"127.0.0.1:41568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "}
{"level":"warn","ts":"2026-03-19T17:57:42.884758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.282574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-19T17:57:42.885306Z","caller":"traceutil/trace.go:171","msg":"trace[522806900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2967; }","duration":"330.861198ms","start":"2026-03-19T17:57:42.554428Z","end":"2026-03-19T17:57:42.885289Z","steps":["trace[522806900] 'agreement among raft nodes before linearized reading'  (duration: 330.283153ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:57:42.885352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:57:42.554416Z","time spent":"330.925491ms","remote":"127.0.0.1:55836","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-03-19T17:57:42.885391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.292282ms","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-03-19T17:57:42.885418Z","caller":"traceutil/trace.go:171","msg":"trace[106506355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2967; }","duration":"286.344903ms","start":"2026-03-19T17:57:42.599067Z","end":"2026-03-19T17:57:42.885412Z","steps":["trace[106506355] 'agreement among raft nodes before linearized reading'  (duration: 286.284972ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:57:42.885143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.729131ms","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-03-19T17:57:42.885557Z","caller":"traceutil/trace.go:171","msg":"trace[215856495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2967; }","duration":"129.177563ms","start":"2026-03-19T17:57:42.756365Z","end":"2026-03-19T17:57:42.885542Z","steps":["trace[215856495] 'agreement among raft nodes before linearized reading'  (duration: 128.566667ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:58:59.626584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.783506ms","expected-duration":"100ms","prefix":"","request":"header:<ID:8079348910898339810 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-bwm25-tls.189e4fe2f7334217\" mod_revision:0 > success:<request_put:<key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-bwm25-tls.189e4fe2f7334217\" value_size:683 lease:8079348910898337745 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-03-19T17:58:59.626705Z","caller":"traceutil/trace.go:171","msg":"trace[2124723493] linearizableReadLoop","detail":"{readStateIndex:4235; appliedIndex:4233; }","duration":"162.972203ms","start":"2026-03-19T17:58:59.463716Z","end":"2026-03-19T17:58:59.626688Z","steps":["trace[2124723493] 'read index received'  (duration: 38.889322ms)","trace[2124723493] 'applied index is now lower than readState.Index'  (duration: 124.081851ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:58:59.62676Z","caller":"traceutil/trace.go:171","msg":"trace[999932213] transaction","detail":"{read_only:false; response_revision:4051; number_of_response:1; }","duration":"164.617364ms","start":"2026-03-19T17:58:59.462135Z","end":"2026-03-19T17:58:59.626753Z","steps":["trace[999932213] 'process raft request'  (duration: 46.595331ms)","trace[999932213] 'compare'  (duration: 117.627683ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T17:58:59.627084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.335541ms","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-03-19T17:58:59.627221Z","caller":"traceutil/trace.go:171","msg":"trace[2142979302] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4051; }","duration":"163.500365ms","start":"2026-03-19T17:58:59.463699Z","end":"2026-03-19T17:58:59.627199Z","steps":["trace[2142979302] 'agreement among raft nodes before linearized reading'  (duration: 163.109846ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:58:59.64266Z","caller":"traceutil/trace.go:171","msg":"trace[1853480831] transaction","detail":"{read_only:false; response_revision:4052; number_of_response:1; }","duration":"124.228403ms","start":"2026-03-19T17:58:59.518391Z","end":"2026-03-19T17:58:59.642619Z","steps":["trace[1853480831] 'process raft request'  (duration: 123.640889ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:58:59.643352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.666242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:48 size:2349409"}
{"level":"info","ts":"2026-03-19T17:58:59.643458Z","caller":"traceutil/trace.go:171","msg":"trace[350790141] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:48; response_revision:4052; }","duration":"108.811196ms","start":"2026-03-19T17:58:59.534629Z","end":"2026-03-19T17:58:59.64344Z","steps":["trace[350790141] 'agreement among raft nodes before linearized reading'  (duration: 107.625235ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:58:59.85392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.164721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"}
{"level":"warn","ts":"2026-03-19T17:58:59.853947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.072805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4737"}
{"level":"info","ts":"2026-03-19T17:58:59.854025Z","caller":"traceutil/trace.go:171","msg":"trace[2017089054] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4052; }","duration":"124.316995ms","start":"2026-03-19T17:58:59.729684Z","end":"2026-03-19T17:58:59.854001Z","steps":["trace[2017089054] 'range keys from in-memory index tree'  (duration: 124.001637ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:58:59.85405Z","caller":"traceutil/trace.go:171","msg":"trace[1286906356] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4052; }","duration":"120.196589ms","start":"2026-03-19T17:58:59.733824Z","end":"2026-03-19T17:58:59.85402Z","steps":["trace[1286906356] 'range keys from in-memory index tree'  (duration: 119.918882ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:00.899818Z","caller":"traceutil/trace.go:171","msg":"trace[1360693180] linearizableReadLoop","detail":"{readStateIndex:4289; appliedIndex:4287; }","duration":"295.613972ms","start":"2026-03-19T17:59:00.604175Z","end":"2026-03-19T17:59:00.899789Z","steps":["trace[1360693180] 'read index received'  (duration: 1.14562ms)","trace[1360693180] 'applied index is now lower than readState.Index'  (duration: 294.466782ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:59:00.899864Z","caller":"traceutil/trace.go:171","msg":"trace[642762989] transaction","detail":"{read_only:false; response_revision:4103; number_of_response:1; }","duration":"297.495061ms","start":"2026-03-19T17:59:00.602313Z","end":"2026-03-19T17:59:00.899808Z","steps":["trace[642762989] 'process raft request'  (duration: 199.265758ms)","trace[642762989] 'compare'  (duration: 97.982597ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:59:00.899968Z","caller":"traceutil/trace.go:171","msg":"trace[1795050023] transaction","detail":"{read_only:false; response_revision:4104; number_of_response:1; }","duration":"296.469574ms","start":"2026-03-19T17:59:00.603486Z","end":"2026-03-19T17:59:00.899956Z","steps":["trace[1795050023] 'process raft request'  (duration: 296.243439ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:59:00.900468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.262879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-gateway-5cc45946cd-76l9p\" ","response":"range_response_count:1 size:3594"}
{"level":"info","ts":"2026-03-19T17:59:00.900591Z","caller":"traceutil/trace.go:171","msg":"trace[672797029] range","detail":"{range_begin:/registry/pods/monitoring/loki-gateway-5cc45946cd-76l9p; range_end:; response_count:1; response_revision:4104; }","duration":"296.431753ms","start":"2026-03-19T17:59:00.604138Z","end":"2026-03-19T17:59:00.900569Z","steps":["trace[672797029] 'agreement among raft nodes before linearized reading'  (duration: 296.059924ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:00.901549Z","caller":"traceutil/trace.go:171","msg":"trace[1551775917] transaction","detail":"{read_only:false; response_revision:4107; number_of_response:1; }","duration":"292.578444ms","start":"2026-03-19T17:59:00.60892Z","end":"2026-03-19T17:59:00.901499Z","steps":["trace[1551775917] 'process raft request'  (duration: 292.400209ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:00.901599Z","caller":"traceutil/trace.go:171","msg":"trace[2090390179] transaction","detail":"{read_only:false; response_revision:4111; number_of_response:1; }","duration":"198.357405ms","start":"2026-03-19T17:59:00.703228Z","end":"2026-03-19T17:59:00.901586Z","steps":["trace[2090390179] 'process raft request'  (duration: 198.303844ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:00.901558Z","caller":"traceutil/trace.go:171","msg":"trace[1995217683] transaction","detail":"{read_only:false; response_revision:4105; number_of_response:1; }","duration":"294.178946ms","start":"2026-03-19T17:59:00.607327Z","end":"2026-03-19T17:59:00.901506Z","steps":["trace[1995217683] 'process raft request'  (duration: 293.843297ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:00.901645Z","caller":"traceutil/trace.go:171","msg":"trace[382853612] transaction","detail":"{read_only:false; response_revision:4106; number_of_response:1; }","duration":"292.777119ms","start":"2026-03-19T17:59:00.608841Z","end":"2026-03-19T17:59:00.901618Z","steps":["trace[382853612] 'process raft request'  (duration: 292.43448ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:00.901825Z","caller":"traceutil/trace.go:171","msg":"trace[1966160018] transaction","detail":"{read_only:false; response_revision:4109; number_of_response:1; }","duration":"291.23306ms","start":"2026-03-19T17:59:00.610581Z","end":"2026-03-19T17:59:00.901814Z","steps":["trace[1966160018] 'process raft request'  (duration: 290.868191ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:00.902012Z","caller":"traceutil/trace.go:171","msg":"trace[335353893] transaction","detail":"{read_only:false; response_revision:4108; number_of_response:1; }","duration":"291.765122ms","start":"2026-03-19T17:59:00.610232Z","end":"2026-03-19T17:59:00.901997Z","steps":["trace[335353893] 'process raft request'  (duration: 291.118555ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:00.901922Z","caller":"traceutil/trace.go:171","msg":"trace[1057205671] transaction","detail":"{read_only:false; response_revision:4110; number_of_response:1; }","duration":"288.660253ms","start":"2026-03-19T17:59:00.613253Z","end":"2026-03-19T17:59:00.901913Z","steps":["trace[1057205671] 'process raft request'  (duration: 288.250103ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:59:00.902931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.926543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/monitoring/loki-gateway\" ","response":"range_response_count:1 size:4362"}
{"level":"info","ts":"2026-03-19T17:59:00.903095Z","caller":"traceutil/trace.go:171","msg":"trace[471901381] range","detail":"{range_begin:/registry/deployments/monitoring/loki-gateway; range_end:; response_count:1; response_revision:4112; }","duration":"298.115038ms","start":"2026-03-19T17:59:00.604917Z","end":"2026-03-19T17:59:00.903032Z","steps":["trace[471901381] 'agreement among raft nodes before linearized reading'  (duration: 297.787539ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:59:00.903504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.714382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/loki\" ","response":"range_response_count:1 size:801"}
{"level":"info","ts":"2026-03-19T17:59:00.903558Z","caller":"traceutil/trace.go:171","msg":"trace[517391802] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/loki; range_end:; response_count:1; response_revision:4112; }","duration":"113.817286ms","start":"2026-03-19T17:59:00.789728Z","end":"2026-03-19T17:59:00.903545Z","steps":["trace[517391802] 'agreement among raft nodes before linearized reading'  (duration: 113.687782ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:59:00.903611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.492962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-03-19T17:59:00.903742Z","caller":"traceutil/trace.go:171","msg":"trace[1753414640] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:4112; }","duration":"207.696626ms","start":"2026-03-19T17:59:00.696017Z","end":"2026-03-19T17:59:00.903714Z","steps":["trace[1753414640] 'agreement among raft nodes before linearized reading'  (duration: 207.48314ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:59:04.259138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.537995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nvs54\" ","response":"range_response_count:1 size:6531"}
{"level":"warn","ts":"2026-03-19T17:59:04.25923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.562035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.228\" ","response":"range_response_count:1 size:135"}
{"level":"info","ts":"2026-03-19T17:59:04.259311Z","caller":"traceutil/trace.go:171","msg":"trace[906544280] range","detail":"{range_begin:/registry/masterleases/199.19.213.228; range_end:; response_count:1; response_revision:4187; }","duration":"439.696828ms","start":"2026-03-19T17:59:03.819593Z","end":"2026-03-19T17:59:04.25929Z","steps":["trace[906544280] 'range keys from in-memory index tree'  (duration: 439.303878ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:59:04.259352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:59:03.819567Z","time spent":"439.776359ms","remote":"127.0.0.1:55880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.228\" "}
{"level":"info","ts":"2026-03-19T17:59:04.259257Z","caller":"traceutil/trace.go:171","msg":"trace[1328938247] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nvs54; range_end:; response_count:1; response_revision:4187; }","duration":"199.71143ms","start":"2026-03-19T17:59:04.059515Z","end":"2026-03-19T17:59:04.259227Z","steps":["trace[1328938247] 'range keys from in-memory index tree'  (duration: 199.314999ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:59:04.259532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.723094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-23066a9a-2ac8-4148-bc8c-c7af6776ceee\" ","response":"range_response_count:1 size:3601"}
{"level":"warn","ts":"2026-03-19T17:59:04.25936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.288584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-bwm25-tls\" ","response":"range_response_count:1 size:2184"}
{"level":"info","ts":"2026-03-19T17:59:04.259567Z","caller":"traceutil/trace.go:171","msg":"trace[178172649] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-23066a9a-2ac8-4148-bc8c-c7af6776ceee; range_end:; response_count:1; response_revision:4187; }","duration":"421.769385ms","start":"2026-03-19T17:59:03.837784Z","end":"2026-03-19T17:59:04.259553Z","steps":["trace[178172649] 'range keys from in-memory index tree'  (duration: 421.616552ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:59:04.259604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:59:03.837772Z","time spent":"421.822766ms","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3624,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-23066a9a-2ac8-4148-bc8c-c7af6776ceee\" "}
{"level":"info","ts":"2026-03-19T17:59:04.259602Z","caller":"traceutil/trace.go:171","msg":"trace[960985936] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-bwm25-tls; range_end:; response_count:1; response_revision:4187; }","duration":"162.555771ms","start":"2026-03-19T17:59:04.097018Z","end":"2026-03-19T17:59:04.259574Z","steps":["trace[960985936] 'range keys from in-memory index tree'  (duration: 162.134541ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:59:04.794129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.758815ms","expected-duration":"100ms","prefix":"","request":"header:<ID:8079348910898340143 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/monitoring/vector-s5vpj.189e4fe42665d136\" mod_revision:0 > success:<request_put:<key:\"/registry/events/monitoring/vector-s5vpj.189e4fe42665d136\" value_size:660 lease:8079348910898337745 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-03-19T17:59:04.794282Z","caller":"traceutil/trace.go:171","msg":"trace[2019932026] linearizableReadLoop","detail":"{readStateIndex:4380; appliedIndex:4379; }","duration":"166.338549ms","start":"2026-03-19T17:59:04.627923Z","end":"2026-03-19T17:59:04.794261Z","steps":["trace[2019932026] 'read index received'  (duration: 49.511µs)","trace[2019932026] 'applied index is now lower than readState.Index'  (duration: 166.287758ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:59:04.794386Z","caller":"traceutil/trace.go:171","msg":"trace[1018859456] transaction","detail":"{read_only:false; response_revision:4191; number_of_response:1; }","duration":"245.945411ms","start":"2026-03-19T17:59:04.548428Z","end":"2026-03-19T17:59:04.794374Z","steps":["trace[1018859456] 'process raft request'  (duration: 46.858078ms)","trace[1018859456] 'compare'  (duration: 198.567029ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T17:59:04.794683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.7519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"}
{"level":"info","ts":"2026-03-19T17:59:04.794766Z","caller":"traceutil/trace.go:171","msg":"trace[103251755] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4191; }","duration":"166.882593ms","start":"2026-03-19T17:59:04.627864Z","end":"2026-03-19T17:59:04.794746Z","steps":["trace[103251755] 'agreement among raft nodes before linearized reading'  (duration: 166.638376ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:59:04.795083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.472538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:781812"}
{"level":"info","ts":"2026-03-19T17:59:04.795148Z","caller":"traceutil/trace.go:171","msg":"trace[672385931] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4191; }","duration":"129.585161ms","start":"2026-03-19T17:59:04.665548Z","end":"2026-03-19T17:59:04.795133Z","steps":["trace[672385931] 'agreement among raft nodes before linearized reading'  (duration: 128.933254ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:06.489367Z","caller":"traceutil/trace.go:171","msg":"trace[1903060509] transaction","detail":"{read_only:false; response_revision:4236; number_of_response:1; }","duration":"100.252355ms","start":"2026-03-19T17:59:06.389084Z","end":"2026-03-19T17:59:06.489336Z","steps":["trace[1903060509] 'process raft request'  (duration: 84.901249ms)","trace[1903060509] 'compare'  (duration: 14.7203ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:59:06.971583Z","caller":"traceutil/trace.go:171","msg":"trace[852739170] linearizableReadLoop","detail":"{readStateIndex:4429; appliedIndex:4428; }","duration":"399.863421ms","start":"2026-03-19T17:59:06.571695Z","end":"2026-03-19T17:59:06.971558Z","steps":["trace[852739170] 'read index received'  (duration: 398.758262ms)","trace[852739170] 'applied index is now lower than readState.Index'  (duration: 1.103699ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:59:06.971715Z","caller":"traceutil/trace.go:171","msg":"trace[1154144300] transaction","detail":"{read_only:false; response_revision:4239; number_of_response:1; }","duration":"409.821717ms","start":"2026-03-19T17:59:06.561882Z","end":"2026-03-19T17:59:06.971704Z","steps":["trace[1154144300] 'process raft request'  (duration: 408.687279ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:59:06.971821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:59:06.561861Z","time spent":"409.879268ms","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13280,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" mod_revision:4055 > success:<request_put:<key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" value_size:13197 >> failure:<request_range:<key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" > >"}
{"level":"warn","ts":"2026-03-19T17:59:06.972029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.338703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"}
{"level":"info","ts":"2026-03-19T17:59:06.972065Z","caller":"traceutil/trace.go:171","msg":"trace[1847063534] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4239; }","duration":"400.375154ms","start":"2026-03-19T17:59:06.571681Z","end":"2026-03-19T17:59:06.972056Z","steps":["trace[1847063534] 'agreement among raft nodes before linearized reading'  (duration: 400.245411ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:59:06.972093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:59:06.571649Z","time spent":"400.438306ms","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11523,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "}
{"level":"warn","ts":"2026-03-19T17:59:06.972437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.019001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-03-19T17:59:06.972532Z","caller":"traceutil/trace.go:171","msg":"trace[28137376] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:4239; }","duration":"268.174705ms","start":"2026-03-19T17:59:06.704337Z","end":"2026-03-19T17:59:06.972512Z","steps":["trace[28137376] 'agreement among raft nodes before linearized reading'  (duration: 267.740334ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:59:06.97253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.579444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"}
{"level":"info","ts":"2026-03-19T17:59:06.972644Z","caller":"traceutil/trace.go:171","msg":"trace[918670867] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4239; }","duration":"152.750928ms","start":"2026-03-19T17:59:06.819865Z","end":"2026-03-19T17:59:06.972616Z","steps":["trace[918670867] 'agreement among raft nodes before linearized reading'  (duration: 152.40728ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:15.854014Z","caller":"traceutil/trace.go:171","msg":"trace[1733093314] linearizableReadLoop","detail":"{readStateIndex:4600; appliedIndex:4599; }","duration":"100.963073ms","start":"2026-03-19T17:59:15.753019Z","end":"2026-03-19T17:59:15.853982Z","steps":["trace[1733093314] 'read index received'  (duration: 100.530153ms)","trace[1733093314] 'applied index is now lower than readState.Index'  (duration: 431.8µs)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:59:15.854071Z","caller":"traceutil/trace.go:171","msg":"trace[1452890949] transaction","detail":"{read_only:false; response_revision:4402; number_of_response:1; }","duration":"146.703542ms","start":"2026-03-19T17:59:15.707302Z","end":"2026-03-19T17:59:15.854005Z","steps":["trace[1452890949] 'process raft request'  (duration: 146.428305ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:59:15.854342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.285852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-19T17:59:15.85452Z","caller":"traceutil/trace.go:171","msg":"trace[76326615] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:4402; }","duration":"101.533918ms","start":"2026-03-19T17:59:15.752961Z","end":"2026-03-19T17:59:15.854495Z","steps":["trace[76326615] 'agreement among raft nodes before linearized reading'  (duration: 101.183159ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:16.001345Z","caller":"traceutil/trace.go:171","msg":"trace[1578219029] transaction","detail":"{read_only:false; response_revision:4404; number_of_response:1; }","duration":"141.606471ms","start":"2026-03-19T17:59:15.859706Z","end":"2026-03-19T17:59:16.001313Z","steps":["trace[1578219029] 'process raft request'  (duration: 74.897621ms)","trace[1578219029] 'compare'  (duration: 66.455624ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:59:16.002166Z","caller":"traceutil/trace.go:171","msg":"trace[1148505883] transaction","detail":"{read_only:false; response_revision:4406; number_of_response:1; }","duration":"132.002753ms","start":"2026-03-19T17:59:15.870146Z","end":"2026-03-19T17:59:16.002148Z","steps":["trace[1148505883] 'process raft request'  (duration: 131.750137ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:16.002162Z","caller":"traceutil/trace.go:171","msg":"trace[1388035133] transaction","detail":"{read_only:false; response_revision:4405; number_of_response:1; }","duration":"132.666731ms","start":"2026-03-19T17:59:15.869456Z","end":"2026-03-19T17:59:16.002123Z","steps":["trace[1388035133] 'process raft request'  (duration: 132.354533ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:17.469131Z","caller":"traceutil/trace.go:171","msg":"trace[145530828] transaction","detail":"{read_only:false; response_revision:4422; number_of_response:1; }","duration":"135.731379ms","start":"2026-03-19T17:59:17.333364Z","end":"2026-03-19T17:59:17.469095Z","steps":["trace[145530828] 'process raft request'  (duration: 135.520604ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:23.749272Z","caller":"traceutil/trace.go:171","msg":"trace[951857843] transaction","detail":"{read_only:false; response_revision:4578; number_of_response:1; }","duration":"192.169449ms","start":"2026-03-19T17:59:23.557073Z","end":"2026-03-19T17:59:23.749243Z","steps":["trace[951857843] 'process raft request'  (duration: 141.216937ms)","trace[951857843] 'compare'  (duration: 50.447058ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:59:23.749371Z","caller":"traceutil/trace.go:171","msg":"trace[757827321] transaction","detail":"{read_only:false; response_revision:4579; number_of_response:1; }","duration":"191.527322ms","start":"2026-03-19T17:59:23.557827Z","end":"2026-03-19T17:59:23.749355Z","steps":["trace[757827321] 'process raft request'  (duration: 191.208114ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:23.749265Z","caller":"traceutil/trace.go:171","msg":"trace[778299221] transaction","detail":"{read_only:false; response_revision:4580; number_of_response:1; }","duration":"191.262484ms","start":"2026-03-19T17:59:23.557964Z","end":"2026-03-19T17:59:23.749226Z","steps":["trace[778299221] 'process raft request'  (duration: 191.13674ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:23.749758Z","caller":"traceutil/trace.go:171","msg":"trace[2000313352] linearizableReadLoop","detail":"{readStateIndex:4785; appliedIndex:4781; }","duration":"191.47862ms","start":"2026-03-19T17:59:23.558248Z","end":"2026-03-19T17:59:23.749726Z","steps":["trace[2000313352] 'read index received'  (duration: 140.055285ms)","trace[2000313352] 'applied index is now lower than readState.Index'  (duration: 51.422185ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T17:59:23.749942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.274535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-19T17:59:23.749988Z","caller":"traceutil/trace.go:171","msg":"trace[1986483395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4583; }","duration":"194.401619ms","start":"2026-03-19T17:59:23.555574Z","end":"2026-03-19T17:59:23.749976Z","steps":["trace[1986483395] 'agreement among raft nodes before linearized reading'  (duration: 194.311886ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:59:23.750011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.11492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"}
{"level":"info","ts":"2026-03-19T17:59:23.750052Z","caller":"traceutil/trace.go:171","msg":"trace[450916539] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4583; }","duration":"240.221693ms","start":"2026-03-19T17:59:23.509819Z","end":"2026-03-19T17:59:23.75004Z","steps":["trace[450916539] 'agreement among raft nodes before linearized reading'  (duration: 240.071219ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:23.750067Z","caller":"traceutil/trace.go:171","msg":"trace[768648618] transaction","detail":"{read_only:false; response_revision:4582; number_of_response:1; }","duration":"187.587405ms","start":"2026-03-19T17:59:23.562461Z","end":"2026-03-19T17:59:23.750048Z","steps":["trace[768648618] 'process raft request'  (duration: 187.138433ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:23.750245Z","caller":"traceutil/trace.go:171","msg":"trace[1192026179] transaction","detail":"{read_only:false; response_revision:4581; number_of_response:1; }","duration":"192.198808ms","start":"2026-03-19T17:59:23.557966Z","end":"2026-03-19T17:59:23.750165Z","steps":["trace[1192026179] 'process raft request'  (duration: 191.205532ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:23.953242Z","caller":"traceutil/trace.go:171","msg":"trace[797006338] transaction","detail":"{read_only:false; response_revision:4584; number_of_response:1; }","duration":"196.895205ms","start":"2026-03-19T17:59:23.756317Z","end":"2026-03-19T17:59:23.953213Z","steps":["trace[797006338] 'process raft request'  (duration: 196.737731ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:24.29482Z","caller":"traceutil/trace.go:171","msg":"trace[1100484037] linearizableReadLoop","detail":"{readStateIndex:4789; appliedIndex:4787; }","duration":"473.955113ms","start":"2026-03-19T17:59:23.820833Z","end":"2026-03-19T17:59:24.294789Z","steps":["trace[1100484037] 'read index received'  (duration: 132.338169ms)","trace[1100484037] 'applied index is now lower than readState.Index'  (duration: 341.615984ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:59:24.294915Z","caller":"traceutil/trace.go:171","msg":"trace[1736299628] transaction","detail":"{read_only:false; response_revision:4585; number_of_response:1; }","duration":"536.766363ms","start":"2026-03-19T17:59:23.758103Z","end":"2026-03-19T17:59:24.294869Z","steps":["trace[1736299628] 'process raft request'  (duration: 536.492366ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:59:24.295127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.274212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.228\" ","response":"range_response_count:1 size:135"}
{"level":"info","ts":"2026-03-19T17:59:24.295168Z","caller":"traceutil/trace.go:171","msg":"trace[24838707] range","detail":"{range_begin:/registry/masterleases/199.19.213.228; range_end:; response_count:1; response_revision:4585; }","duration":"474.377105ms","start":"2026-03-19T17:59:23.820775Z","end":"2026-03-19T17:59:24.295152Z","steps":["trace[24838707] 'agreement among raft nodes before linearized reading'  (duration: 474.188839ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:59:24.295154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:59:23.758064Z","time spent":"536.978129ms","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3748,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5539a0d1-25d7-4847-b5b9-e61958244583\" mod_revision:4555 > success:<request_put:<key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5539a0d1-25d7-4847-b5b9-e61958244583\" value_size:3648 >> failure:<request_range:<key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5539a0d1-25d7-4847-b5b9-e61958244583\" > >"}
{"level":"warn","ts":"2026-03-19T17:59:24.295215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:59:23.820748Z","time spent":"474.459997ms","remote":"127.0.0.1:55880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.228\" "}
{"level":"warn","ts":"2026-03-19T17:59:24.295395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.046224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-03-19T17:59:24.295456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.814959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"}
{"level":"info","ts":"2026-03-19T17:59:24.295552Z","caller":"traceutil/trace.go:171","msg":"trace[316614785] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:4585; }","duration":"470.975824ms","start":"2026-03-19T17:59:23.824554Z","end":"2026-03-19T17:59:24.29553Z","steps":["trace[316614785] 'agreement among raft nodes before linearized reading'  (duration: 470.647896ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:59:24.295606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:59:23.824529Z","time spent":"471.068177ms","remote":"127.0.0.1:55988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1804,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "}
{"level":"info","ts":"2026-03-19T17:59:24.295565Z","caller":"traceutil/trace.go:171","msg":"trace[106535502] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:4585; }","duration":"168.26732ms","start":"2026-03-19T17:59:24.127237Z","end":"2026-03-19T17:59:24.295504Z","steps":["trace[106535502] 'agreement among raft nodes before linearized reading'  (duration: 168.014913ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T17:59:32.115568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.802741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"}
{"level":"info","ts":"2026-03-19T17:59:32.115781Z","caller":"traceutil/trace.go:171","msg":"trace[2088873189] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4713; }","duration":"127.109888ms","start":"2026-03-19T17:59:31.988645Z","end":"2026-03-19T17:59:32.115755Z","steps":["trace[2088873189] 'range keys from in-memory index tree'  (duration: 126.431311ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:37.589272Z","caller":"traceutil/trace.go:171","msg":"trace[1506278539] linearizableReadLoop","detail":"{readStateIndex:4967; appliedIndex:4965; }","duration":"104.562136ms","start":"2026-03-19T17:59:37.484683Z","end":"2026-03-19T17:59:37.589245Z","steps":["trace[1506278539] 'read index received'  (duration: 40.707158ms)","trace[1506278539] 'applied index is now lower than readState.Index'  (duration: 63.853958ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T17:59:37.589313Z","caller":"traceutil/trace.go:171","msg":"trace[1255062422] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"107.103284ms","start":"2026-03-19T17:59:37.48217Z","end":"2026-03-19T17:59:37.589273Z","steps":["trace[1255062422] 'process raft request'  (duration: 106.999671ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:37.589281Z","caller":"traceutil/trace.go:171","msg":"trace[1616678037] transaction","detail":"{read_only:false; response_revision:4757; number_of_response:1; }","duration":"107.851124ms","start":"2026-03-19T17:59:37.481404Z","end":"2026-03-19T17:59:37.589255Z","steps":["trace[1616678037] 'process raft request'  (duration: 43.970535ms)","trace[1616678037] 'compare'  (duration: 63.603601ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T17:59:37.589642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.881724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"}
{"level":"info","ts":"2026-03-19T17:59:37.589738Z","caller":"traceutil/trace.go:171","msg":"trace[353075748] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4758; }","duration":"105.071419ms","start":"2026-03-19T17:59:37.484642Z","end":"2026-03-19T17:59:37.589714Z","steps":["trace[353075748] 'agreement among raft nodes before linearized reading'  (duration: 104.70316ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T17:59:38.107863Z","caller":"traceutil/trace.go:171","msg":"trace[1325505854] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"126.866432ms","start":"2026-03-19T17:59:37.98096Z","end":"2026-03-19T17:59:38.107827Z","steps":["trace[1325505854] 'process raft request'  (duration: 84.670954ms)","trace[1325505854] 'compare'  (duration: 42.002883ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T18:00:19.453018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.584813ms","expected-duration":"100ms","prefix":"","request":"header:<ID:8079348910898342583 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/services/endpoints/monitoring/loki-memberlist\" mod_revision:4590 > success:<request_put:<key:\"/registry/services/endpoints/monitoring/loki-memberlist\" value_size:905 >> failure:<request_range:<key:\"/registry/services/endpoints/monitoring/loki-memberlist\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-19T18:00:19.453149Z","caller":"traceutil/trace.go:171","msg":"trace[1105541063] transaction","detail":"{read_only:false; response_revision:5136; number_of_response:1; }","duration":"255.868271ms","start":"2026-03-19T18:00:19.19726Z","end":"2026-03-19T18:00:19.453128Z","steps":["trace[1105541063] 'process raft request'  (duration: 18.045182ms)","trace[1105541063] 'compare'  (duration: 237.43326ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:00:19.466179Z","caller":"traceutil/trace.go:171","msg":"trace[1141409422] transaction","detail":"{read_only:false; response_revision:5138; number_of_response:1; }","duration":"268.749996ms","start":"2026-03-19T18:00:19.197396Z","end":"2026-03-19T18:00:19.466146Z","steps":["trace[1141409422] 'process raft request'  (duration: 268.650593ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:00:19.466192Z","caller":"traceutil/trace.go:171","msg":"trace[663699709] transaction","detail":"{read_only:false; response_revision:5137; number_of_response:1; }","duration":"268.853029ms","start":"2026-03-19T18:00:19.197304Z","end":"2026-03-19T18:00:19.466157Z","steps":["trace[663699709] 'process raft request'  (duration: 268.559281ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:00:19.466513Z","caller":"traceutil/trace.go:171","msg":"trace[905693670] transaction","detail":"{read_only:false; response_revision:5139; number_of_response:1; }","duration":"268.986213ms","start":"2026-03-19T18:00:19.197507Z","end":"2026-03-19T18:00:19.466493Z","steps":["trace[905693670] 'process raft request'  (duration: 268.595893ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:00:19.466641Z","caller":"traceutil/trace.go:171","msg":"trace[683839770] transaction","detail":"{read_only:false; response_revision:5141; number_of_response:1; }","duration":"268.599041ms","start":"2026-03-19T18:00:19.198006Z","end":"2026-03-19T18:00:19.466605Z","steps":["trace[683839770] 'process raft request'  (duration: 268.452788ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:00:19.46658Z","caller":"traceutil/trace.go:171","msg":"trace[1745213568] transaction","detail":"{read_only:false; response_revision:5140; number_of_response:1; }","duration":"268.720046ms","start":"2026-03-19T18:00:19.197826Z","end":"2026-03-19T18:00:19.466546Z","steps":["trace[1745213568] 'process raft request'  (duration: 268.446609ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:00:19.466819Z","caller":"traceutil/trace.go:171","msg":"trace[728743910] transaction","detail":"{read_only:false; response_revision:5142; number_of_response:1; }","duration":"266.712942ms","start":"2026-03-19T18:00:19.200078Z","end":"2026-03-19T18:00:19.466791Z","steps":["trace[728743910] 'process raft request'  (duration: 266.462565ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:00:19.467116Z","caller":"traceutil/trace.go:171","msg":"trace[397219180] transaction","detail":"{read_only:false; response_revision:5143; number_of_response:1; }","duration":"266.256189ms","start":"2026-03-19T18:00:19.200848Z","end":"2026-03-19T18:00:19.467104Z","steps":["trace[397219180] 'process raft request'  (duration: 265.847338ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:00:54.660785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.846365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-19T18:00:54.660944Z","caller":"traceutil/trace.go:171","msg":"trace[45163391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5441; }","duration":"107.085031ms","start":"2026-03-19T18:00:54.553828Z","end":"2026-03-19T18:00:54.660913Z","steps":["trace[45163391] 'range keys from in-memory index tree'  (duration: 106.731893ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:02:50.69103Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3018}
{"level":"info","ts":"2026-03-19T18:02:50.768876Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3018,"took":"76.743252ms","hash":1071598480,"current-db-size-bytes":44158976,"current-db-size":"44 MB","current-db-size-in-use-bytes":30597120,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-03-19T18:02:50.768971Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1071598480,"revision":3018,"compact-revision":-1}
{"level":"info","ts":"2026-03-19T18:05:11.095014Z","caller":"traceutil/trace.go:171","msg":"trace[1936290944] transaction","detail":"{read_only:false; response_revision:7582; number_of_response:1; }","duration":"243.775007ms","start":"2026-03-19T18:05:10.851179Z","end":"2026-03-19T18:05:11.094954Z","steps":["trace[1936290944] 'process raft request'  (duration: 243.598433ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:05:11.098313Z","caller":"traceutil/trace.go:171","msg":"trace[1735526517] linearizableReadLoop","detail":"{readStateIndex:7936; appliedIndex:7934; }","duration":"108.865508ms","start":"2026-03-19T18:05:10.989419Z","end":"2026-03-19T18:05:11.098285Z","steps":["trace[1735526517] 'read index received'  (duration: 105.45495ms)","trace[1735526517] 'applied index is now lower than readState.Index'  (duration: 3.409518ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:05:11.09841Z","caller":"traceutil/trace.go:171","msg":"trace[1706792296] transaction","detail":"{read_only:false; response_revision:7583; number_of_response:1; }","duration":"239.112668ms","start":"2026-03-19T18:05:10.859263Z","end":"2026-03-19T18:05:11.098375Z","steps":["trace[1706792296] 'process raft request'  (duration: 238.856511ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:05:11.098679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.236247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"}
{"level":"info","ts":"2026-03-19T18:05:11.098791Z","caller":"traceutil/trace.go:171","msg":"trace[1939838617] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7583; }","duration":"109.34116ms","start":"2026-03-19T18:05:10.989382Z","end":"2026-03-19T18:05:11.098723Z","steps":["trace[1939838617] 'agreement among raft nodes before linearized reading'  (duration: 109.159316ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:05:11.552086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.268037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"}
{"level":"info","ts":"2026-03-19T18:05:11.552182Z","caller":"traceutil/trace.go:171","msg":"trace[640862115] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7591; }","duration":"247.40638ms","start":"2026-03-19T18:05:11.304755Z","end":"2026-03-19T18:05:11.552161Z","steps":["trace[640862115] 'range keys from in-memory index tree'  (duration: 247.079472ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:05:13.369572Z","caller":"traceutil/trace.go:171","msg":"trace[66752009] linearizableReadLoop","detail":"{readStateIndex:7956; appliedIndex:7955; }","duration":"144.614781ms","start":"2026-03-19T18:05:13.224937Z","end":"2026-03-19T18:05:13.369551Z","steps":["trace[66752009] 'read index received'  (duration: 144.529439ms)","trace[66752009] 'applied index is now lower than readState.Index'  (duration: 84.572µs)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:05:13.369673Z","caller":"traceutil/trace.go:171","msg":"trace[1801112200] transaction","detail":"{read_only:false; response_revision:7602; number_of_response:1; }","duration":"240.531323ms","start":"2026-03-19T18:05:13.129124Z","end":"2026-03-19T18:05:13.369655Z","steps":["trace[1801112200] 'process raft request'  (duration: 240.299148ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:05:13.369737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.787105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"}
{"level":"info","ts":"2026-03-19T18:05:13.369762Z","caller":"traceutil/trace.go:171","msg":"trace[1787162451] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:7602; }","duration":"144.859167ms","start":"2026-03-19T18:05:13.224896Z","end":"2026-03-19T18:05:13.369755Z","steps":["trace[1787162451] 'agreement among raft nodes before linearized reading'  (duration: 144.733594ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:05:13.634162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.438263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"}
{"level":"info","ts":"2026-03-19T18:05:13.634279Z","caller":"traceutil/trace.go:171","msg":"trace[469631890] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7602; }","duration":"261.610198ms","start":"2026-03-19T18:05:13.372639Z","end":"2026-03-19T18:05:13.63425Z","steps":["trace[469631890] 'range keys from in-memory index tree'  (duration: 261.246018ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:05:13.634302Z","caller":"traceutil/trace.go:171","msg":"trace[1592388303] transaction","detail":"{read_only:false; response_revision:7603; number_of_response:1; }","duration":"260.810536ms","start":"2026-03-19T18:05:13.37346Z","end":"2026-03-19T18:05:13.634271Z","steps":["trace[1592388303] 'process raft request'  (duration: 183.079731ms)","trace[1592388303] 'compare'  (duration: 77.551911ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:05:52.359366Z","caller":"traceutil/trace.go:171","msg":"trace[1202100706] linearizableReadLoop","detail":"{readStateIndex:8263; appliedIndex:8262; }","duration":"291.75819ms","start":"2026-03-19T18:05:52.067584Z","end":"2026-03-19T18:05:52.359342Z","steps":["trace[1202100706] 'read index received'  (duration: 291.543404ms)","trace[1202100706] 'applied index is now lower than readState.Index'  (duration: 213.566µs)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:05:52.359754Z","caller":"traceutil/trace.go:171","msg":"trace[625002206] transaction","detail":"{read_only:false; response_revision:7892; number_of_response:1; }","duration":"393.311504ms","start":"2026-03-19T18:05:51.966431Z","end":"2026-03-19T18:05:52.359742Z","steps":["trace[625002206] 'process raft request'  (duration: 392.7777ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:05:52.359894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:05:51.966406Z","time spent":"393.409507ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:7879 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-03-19T18:05:52.360138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.857435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"info","ts":"2026-03-19T18:05:52.360211Z","caller":"traceutil/trace.go:171","msg":"trace[1637403081] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7892; }","duration":"236.979088ms","start":"2026-03-19T18:05:52.123216Z","end":"2026-03-19T18:05:52.360195Z","steps":["trace[1637403081] 'agreement among raft nodes before linearized reading'  (duration: 236.778723ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:05:52.36029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.094074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"}
{"level":"info","ts":"2026-03-19T18:05:52.360333Z","caller":"traceutil/trace.go:171","msg":"trace[249923364] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7892; }","duration":"133.160616ms","start":"2026-03-19T18:05:52.227158Z","end":"2026-03-19T18:05:52.360318Z","steps":["trace[249923364] 'agreement among raft nodes before linearized reading'  (duration: 133.030901ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:05:52.36039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.264321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-19T18:05:52.360418Z","caller":"traceutil/trace.go:171","msg":"trace[183388994] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:7892; }","duration":"216.316173ms","start":"2026-03-19T18:05:52.144092Z","end":"2026-03-19T18:05:52.360408Z","steps":["trace[183388994] 'agreement among raft nodes before linearized reading'  (duration: 216.266862ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:05:52.360531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.972522ms","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-03-19T18:05:52.360561Z","caller":"traceutil/trace.go:171","msg":"trace[921267086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7892; }","duration":"293.045473ms","start":"2026-03-19T18:05:52.067506Z","end":"2026-03-19T18:05:52.360552Z","steps":["trace[921267086] 'agreement among raft nodes before linearized reading'  (duration: 292.963281ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:05:52.360582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.088755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-03-19T18:05:52.360606Z","caller":"traceutil/trace.go:171","msg":"trace[361297513] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7892; }","duration":"236.134616ms","start":"2026-03-19T18:05:52.124464Z","end":"2026-03-19T18:05:52.360599Z","steps":["trace[361297513] 'agreement among raft nodes before linearized reading'  (duration: 236.066985ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:05:52.360713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.853695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-03-19T18:05:52.360737Z","caller":"traceutil/trace.go:171","msg":"trace[207241437] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7892; }","duration":"236.901476ms","start":"2026-03-19T18:05:52.12383Z","end":"2026-03-19T18:05:52.360731Z","steps":["trace[207241437] 'agreement among raft nodes before linearized reading'  (duration: 236.833344ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:05:52.360856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.842055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"}
{"level":"info","ts":"2026-03-19T18:05:52.360897Z","caller":"traceutil/trace.go:171","msg":"trace[1793105311] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7892; }","duration":"132.891807ms","start":"2026-03-19T18:05:52.227995Z","end":"2026-03-19T18:05:52.360887Z","steps":["trace[1793105311] 'agreement among raft nodes before linearized reading'  (duration: 132.806145ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:05:52.900157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.955253ms","expected-duration":"100ms","prefix":"","request":"header:<ID:8079348910898350408 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:7881 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:429 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-19T18:05:52.900324Z","caller":"traceutil/trace.go:171","msg":"trace[442340043] transaction","detail":"{read_only:false; response_revision:7893; number_of_response:1; }","duration":"536.316395ms","start":"2026-03-19T18:05:52.36398Z","end":"2026-03-19T18:05:52.900297Z","steps":["trace[442340043] 'process raft request'  (duration: 315.093035ms)","trace[442340043] 'compare'  (duration: 220.559002ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T18:05:52.900407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:05:52.363961Z","time spent":"536.403456ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:7881 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:429 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-03-19T18:05:52.939772Z","caller":"traceutil/trace.go:171","msg":"trace[455802943] transaction","detail":"{read_only:false; response_revision:7894; number_of_response:1; }","duration":"574.720371ms","start":"2026-03-19T18:05:52.365001Z","end":"2026-03-19T18:05:52.939722Z","steps":["trace[455802943] 'process raft request'  (duration: 574.361893ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:05:52.939902Z","caller":"traceutil/trace.go:171","msg":"trace[2004949433] transaction","detail":"{read_only:false; response_revision:7895; number_of_response:1; }","duration":"574.763063ms","start":"2026-03-19T18:05:52.365094Z","end":"2026-03-19T18:05:52.939857Z","steps":["trace[2004949433] 'process raft request'  (duration: 574.452995ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:05:52.939898Z","caller":"traceutil/trace.go:171","msg":"trace[1668120384] transaction","detail":"{read_only:false; response_revision:7896; number_of_response:1; }","duration":"574.627108ms","start":"2026-03-19T18:05:52.365242Z","end":"2026-03-19T18:05:52.93987Z","steps":["trace[1668120384] 'process raft request'  (duration: 574.356261ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:05:52.940018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:05:52.364981Z","time spent":"574.908816ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:7882 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-03-19T18:05:52.940074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:05:52.365234Z","time spent":"574.750002ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:7888 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"warn","ts":"2026-03-19T18:05:52.940078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:05:52.365086Z","time spent":"574.915567ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:7880 > 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-03-19T18:05:52.940108Z","caller":"traceutil/trace.go:171","msg":"trace[1718346878] linearizableReadLoop","detail":"{readStateIndex:8267; appliedIndex:8263; }","duration":"568.276404ms","start":"2026-03-19T18:05:52.371809Z","end":"2026-03-19T18:05:52.940086Z","steps":["trace[1718346878] 'read index received'  (duration: 307.229051ms)","trace[1718346878] 'applied index is now lower than readState.Index'  (duration: 261.046063ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:05:52.940203Z","caller":"traceutil/trace.go:171","msg":"trace[409455339] transaction","detail":"{read_only:false; response_revision:7898; number_of_response:1; }","duration":"566.517098ms","start":"2026-03-19T18:05:52.373671Z","end":"2026-03-19T18:05:52.940188Z","steps":["trace[409455339] 'process raft request'  (duration: 566.290992ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:05:52.940205Z","caller":"traceutil/trace.go:171","msg":"trace[1716182707] transaction","detail":"{read_only:false; response_revision:7897; number_of_response:1; }","duration":"566.496468ms","start":"2026-03-19T18:05:52.373649Z","end":"2026-03-19T18:05:52.940145Z","steps":["trace[1716182707] 'process raft request'  (duration: 565.997675ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:05:52.940305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.021618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-03-19T18:05:52.940334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.50271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"}
{"level":"info","ts":"2026-03-19T18:05:52.940373Z","caller":"traceutil/trace.go:171","msg":"trace[703181695] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7898; }","duration":"568.569121ms","start":"2026-03-19T18:05:52.371792Z","end":"2026-03-19T18:05:52.940361Z","steps":["trace[703181695] 'agreement among raft nodes before linearized reading'  (duration: 568.428468ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:05:52.940374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.584559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nvs54\" ","response":"range_response_count:1 size:6531"}
{"level":"info","ts":"2026-03-19T18:05:52.940369Z","caller":"traceutil/trace.go:171","msg":"trace[438698526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7898; }","duration":"383.1125ms","start":"2026-03-19T18:05:52.557238Z","end":"2026-03-19T18:05:52.940351Z","steps":["trace[438698526] 'agreement among raft nodes before linearized reading'  (duration: 383.024998ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:05:52.940403Z","caller":"traceutil/trace.go:171","msg":"trace[1959191890] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nvs54; range_end:; response_count:1; response_revision:7898; }","duration":"307.643951ms","start":"2026-03-19T18:05:52.632751Z","end":"2026-03-19T18:05:52.940395Z","steps":["trace[1959191890] 'agreement among raft nodes before linearized reading'  (duration: 307.541948ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:05:52.940405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:05:52.371785Z","time spent":"568.613982ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":590,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "}
{"level":"warn","ts":"2026-03-19T18:05:52.940414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:05:52.557215Z","time spent":"383.192531ms","remote":"127.0.0.1:55836","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-03-19T18:05:52.940431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:05:52.632731Z","time spent":"307.694693ms","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-nvs54\" "}
{"level":"warn","ts":"2026-03-19T18:05:52.940405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:05:52.373643Z","time spent":"566.656802ms","remote":"127.0.0.1:55938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:7884 > success:<request_put:<key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" value_size:549 >> failure:<request_range:<key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-03-19T18:05:52.940496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.037496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"}
{"level":"warn","ts":"2026-03-19T18:05:52.940261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:05:52.373663Z","time spent":"566.567949ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:7883 > 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-03-19T18:05:52.940545Z","caller":"traceutil/trace.go:171","msg":"trace[1775750777] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7898; }","duration":"297.101238ms","start":"2026-03-19T18:05:52.64343Z","end":"2026-03-19T18:05:52.940531Z","steps":["trace[1775750777] 'agreement among raft nodes before linearized reading'  (duration: 296.836232ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:06:05.030466Z","caller":"traceutil/trace.go:171","msg":"trace[1493158684] transaction","detail":"{read_only:false; response_revision:8011; number_of_response:1; }","duration":"107.964891ms","start":"2026-03-19T18:06:04.922469Z","end":"2026-03-19T18:06:05.030434Z","steps":["trace[1493158684] 'process raft request'  (duration: 32.715269ms)","trace[1493158684] 'compare'  (duration: 74.872353ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:06:05.295841Z","caller":"traceutil/trace.go:171","msg":"trace[146319486] linearizableReadLoop","detail":"{readStateIndex:8398; appliedIndex:8397; }","duration":"167.956957ms","start":"2026-03-19T18:06:05.127862Z","end":"2026-03-19T18:06:05.295819Z","steps":["trace[146319486] 'read index received'  (duration: 167.853065ms)","trace[146319486] 'applied index is now lower than readState.Index'  (duration: 102.952µs)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:06:05.295948Z","caller":"traceutil/trace.go:171","msg":"trace[1575257017] transaction","detail":"{read_only:false; response_revision:8018; number_of_response:1; }","duration":"177.67568ms","start":"2026-03-19T18:06:05.118215Z","end":"2026-03-19T18:06:05.29589Z","steps":["trace[1575257017] 'process raft request'  (duration: 177.455764ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:05.296204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.320808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"}
{"level":"info","ts":"2026-03-19T18:06:05.296272Z","caller":"traceutil/trace.go:171","msg":"trace[1180985325] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8018; }","duration":"168.443991ms","start":"2026-03-19T18:06:05.127814Z","end":"2026-03-19T18:06:05.296258Z","steps":["trace[1180985325] 'agreement among raft nodes before linearized reading'  (duration: 168.111203ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:06:06.211229Z","caller":"traceutil/trace.go:171","msg":"trace[298008119] transaction","detail":"{read_only:false; response_revision:8021; number_of_response:1; }","duration":"413.95795ms","start":"2026-03-19T18:06:05.797248Z","end":"2026-03-19T18:06:06.211206Z","steps":["trace[298008119] 'process raft request'  (duration: 413.810566ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:06.211368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:05.797221Z","time spent":"414.073883ms","remote":"127.0.0.1:55918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/events/openstack/rook-ceph-detect-version-qfb2t.189e50463abc6d41\" mod_revision:0 > success:<request_put:<key:\"/registry/events/openstack/rook-ceph-detect-version-qfb2t.189e50463abc6d41\" value_size:654 lease:8079348910898349590 >> failure:<>"}
{"level":"info","ts":"2026-03-19T18:06:06.459121Z","caller":"traceutil/trace.go:171","msg":"trace[914870919] transaction","detail":"{read_only:false; response_revision:8022; number_of_response:1; }","duration":"653.497155ms","start":"2026-03-19T18:06:05.805577Z","end":"2026-03-19T18:06:06.459074Z","steps":["trace[914870919] 'process raft request'  (duration: 625.206952ms)","trace[914870919] 'compare'  (duration: 28.031687ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T18:06:06.459359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:05.805566Z","time spent":"653.6547ms","remote":"127.0.0.1:56058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2999,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/jobs/openstack/rook-ceph-detect-version\" mod_revision:8006 > success:<request_put:<key:\"/registry/jobs/openstack/rook-ceph-detect-version\" value_size:2942 >> failure:<request_range:<key:\"/registry/jobs/openstack/rook-ceph-detect-version\" > >"}
{"level":"warn","ts":"2026-03-19T18:06:07.615314Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8079348910898350790,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-03-19T18:06:07.779601Z","caller":"traceutil/trace.go:171","msg":"trace[990559432] linearizableReadLoop","detail":"{readStateIndex:8406; appliedIndex:8405; }","duration":"664.629203ms","start":"2026-03-19T18:06:07.11492Z","end":"2026-03-19T18:06:07.779549Z","steps":["trace[990559432] 'read index received'  (duration: 664.333236ms)","trace[990559432] 'applied index is now lower than readState.Index'  (duration: 294.617µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T18:06:07.779861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.920381ms","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-03-19T18:06:07.779891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.145782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"}
{"level":"info","ts":"2026-03-19T18:06:07.779914Z","caller":"traceutil/trace.go:171","msg":"trace[213897797] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8026; }","duration":"665.021164ms","start":"2026-03-19T18:06:07.114876Z","end":"2026-03-19T18:06:07.779898Z","steps":["trace[213897797] 'agreement among raft nodes before linearized reading'  (duration: 664.855239ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:07.779953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.722973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-03-19T18:06:07.779958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:07.114855Z","time spent":"665.093536ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"info","ts":"2026-03-19T18:06:07.779978Z","caller":"traceutil/trace.go:171","msg":"trace[858338927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8026; }","duration":"226.774794ms","start":"2026-03-19T18:06:07.553198Z","end":"2026-03-19T18:06:07.779973Z","steps":["trace[858338927] 'agreement among raft nodes before linearized reading'  (duration: 226.738823ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:07.780059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.000684ms","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-03-19T18:06:07.77992Z","caller":"traceutil/trace.go:171","msg":"trace[368267967] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8026; }","duration":"464.178113ms","start":"2026-03-19T18:06:07.315733Z","end":"2026-03-19T18:06:07.779911Z","steps":["trace[368267967] 'agreement among raft nodes before linearized reading'  (duration: 464.091911ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:07.78014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.698522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-03-19T18:06:07.78013Z","caller":"traceutil/trace.go:171","msg":"trace[736031139] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8026; }","duration":"665.169958ms","start":"2026-03-19T18:06:07.114947Z","end":"2026-03-19T18:06:07.780117Z","steps":["trace[736031139] 'agreement among raft nodes before linearized reading'  (duration: 664.829409ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:06:07.780179Z","caller":"traceutil/trace.go:171","msg":"trace[1885063457] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:8026; }","duration":"384.792125ms","start":"2026-03-19T18:06:07.395379Z","end":"2026-03-19T18:06:07.780171Z","steps":["trace[1885063457] 'agreement among raft nodes before linearized reading'  (duration: 384.712123ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:07.780201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:07.395357Z","time spent":"384.837265ms","remote":"127.0.0.1:56352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":37,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "}
{"level":"warn","ts":"2026-03-19T18:06:07.7802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:07.114923Z","time spent":"665.26672ms","remote":"127.0.0.1:56092","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-03-19T18:06:07.780145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:07.315669Z","time spent":"464.465201ms","remote":"127.0.0.1:55938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"warn","ts":"2026-03-19T18:06:07.780585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.94926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nvs54\" ","response":"range_response_count:1 size:6531"}
{"level":"warn","ts":"2026-03-19T18:06:07.780667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.110976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"info","ts":"2026-03-19T18:06:07.78068Z","caller":"traceutil/trace.go:171","msg":"trace[921522918] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nvs54; range_end:; response_count:1; response_revision:8026; }","duration":"663.222988ms","start":"2026-03-19T18:06:07.117447Z","end":"2026-03-19T18:06:07.78067Z","steps":["trace[921522918] 'agreement among raft nodes before linearized reading'  (duration: 663.058073ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:06:07.780702Z","caller":"traceutil/trace.go:171","msg":"trace[1027888681] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8026; }","duration":"665.169348ms","start":"2026-03-19T18:06:07.115522Z","end":"2026-03-19T18:06:07.780691Z","steps":["trace[1027888681] 'agreement among raft nodes before linearized reading'  (duration: 665.023775ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:07.780738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:07.115511Z","time spent":"665.220589ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-03-19T18:06:07.780994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.225689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"}
{"level":"info","ts":"2026-03-19T18:06:07.781362Z","caller":"traceutil/trace.go:171","msg":"trace[1767844971] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8026; }","duration":"665.604399ms","start":"2026-03-19T18:06:07.115737Z","end":"2026-03-19T18:06:07.781342Z","steps":["trace[1767844971] 'agreement among raft nodes before linearized reading'  (duration: 665.098366ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:07.781462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:07.115705Z","time spent":"665.743443ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "}
{"level":"warn","ts":"2026-03-19T18:06:07.781789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:07.117426Z","time spent":"664.345497ms","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-nvs54\" "}
{"level":"info","ts":"2026-03-19T18:06:07.782806Z","caller":"traceutil/trace.go:171","msg":"trace[1912896166] transaction","detail":"{read_only:false; response_revision:8026; number_of_response:1; }","duration":"680.519945ms","start":"2026-03-19T18:06:07.099159Z","end":"2026-03-19T18:06:07.779679Z","steps":["trace[1912896166] 'process raft request'  (duration: 680.212138ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:07.782917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.348038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"}
{"level":"info","ts":"2026-03-19T18:06:07.78304Z","caller":"traceutil/trace.go:171","msg":"trace[1259312795] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8026; }","duration":"454.455141ms","start":"2026-03-19T18:06:07.328521Z","end":"2026-03-19T18:06:07.782976Z","steps":["trace[1259312795] 'agreement among raft nodes before linearized reading'  (duration: 451.778632ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:07.783524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:07.328479Z","time spent":"454.592064ms","remote":"127.0.0.1:55938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "}
{"level":"warn","ts":"2026-03-19T18:06:07.78568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:07.099141Z","time spent":"683.75151ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:8012 > 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-03-19T18:06:08.046204Z","caller":"traceutil/trace.go:171","msg":"trace[31537565] transaction","detail":"{read_only:false; response_revision:8027; number_of_response:1; }","duration":"260.698874ms","start":"2026-03-19T18:06:07.78547Z","end":"2026-03-19T18:06:08.046169Z","steps":["trace[31537565] 'process raft request'  (duration: 242.269506ms)","trace[31537565] 'compare'  (duration: 18.181142ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:06:08.090962Z","caller":"traceutil/trace.go:171","msg":"trace[719820811] transaction","detail":"{read_only:false; response_revision:8028; number_of_response:1; }","duration":"305.267091ms","start":"2026-03-19T18:06:07.785669Z","end":"2026-03-19T18:06:08.090936Z","steps":["trace[719820811] 'process raft request'  (duration: 305.086187ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:08.091153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:07.785658Z","time spent":"305.410394ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:8013 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:429 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-03-19T18:06:08.091348Z","caller":"traceutil/trace.go:171","msg":"trace[1458673348] transaction","detail":"{read_only:false; response_revision:8029; number_of_response:1; }","duration":"305.593388ms","start":"2026-03-19T18:06:07.785725Z","end":"2026-03-19T18:06:08.091319Z","steps":["trace[1458673348] 'process raft request'  (duration: 305.172968ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:06:08.091415Z","caller":"traceutil/trace.go:171","msg":"trace[2008927062] transaction","detail":"{read_only:false; response_revision:8030; number_of_response:1; }","duration":"305.630811ms","start":"2026-03-19T18:06:07.78575Z","end":"2026-03-19T18:06:08.091381Z","steps":["trace[2008927062] 'process raft request'  (duration: 305.425255ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:08.091494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:07.785718Z","time spent":"305.699013ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:8014 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-03-19T18:06:08.09152Z","caller":"traceutil/trace.go:171","msg":"trace[284634390] linearizableReadLoop","detail":"{readStateIndex:8410; appliedIndex:8406; }","duration":"304.259635ms","start":"2026-03-19T18:06:07.787237Z","end":"2026-03-19T18:06:08.091497Z","steps":["trace[284634390] 'read index received'  (duration: 240.399747ms)","trace[284634390] 'applied index is now lower than readState.Index'  (duration: 63.858798ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T18:06:08.091611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:07.785743Z","time spent":"305.761324ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:8015 > 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-03-19T18:06:08.091602Z","caller":"traceutil/trace.go:171","msg":"trace[1271470794] transaction","detail":"{read_only:false; response_revision:8031; number_of_response:1; }","duration":"302.834088ms","start":"2026-03-19T18:06:07.788738Z","end":"2026-03-19T18:06:08.091572Z","steps":["trace[1271470794] 'process raft request'  (duration: 302.57681ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:08.091717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:07.78873Z","time spent":"302.936791ms","remote":"127.0.0.1:55938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:8017 > success:<request_put:<key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" value_size:549 >> failure:<request_range:<key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-03-19T18:06:08.091805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.540162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"}
{"level":"info","ts":"2026-03-19T18:06:08.091856Z","caller":"traceutil/trace.go:171","msg":"trace[1115791975] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8031; }","duration":"304.615844ms","start":"2026-03-19T18:06:07.787222Z","end":"2026-03-19T18:06:08.091838Z","steps":["trace[1115791975] 'agreement among raft nodes before linearized reading'  (duration: 304.430269ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:08.091891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:07.787215Z","time spent":"304.667865ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":590,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "}
{"level":"warn","ts":"2026-03-19T18:06:08.092408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.871623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:208111"}
{"level":"info","ts":"2026-03-19T18:06:08.092459Z","caller":"traceutil/trace.go:171","msg":"trace[70099527] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8031; }","duration":"301.938804ms","start":"2026-03-19T18:06:07.790507Z","end":"2026-03-19T18:06:08.092446Z","steps":["trace[70099527] 'agreement among raft nodes before linearized reading'  (duration: 301.559575ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:08.092495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:07.790501Z","time spent":"301.984845ms","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":25,"response size":208134,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "}
{"level":"info","ts":"2026-03-19T18:06:08.662513Z","caller":"traceutil/trace.go:171","msg":"trace[1758703519] linearizableReadLoop","detail":"{readStateIndex:8417; appliedIndex:8416; }","duration":"224.130585ms","start":"2026-03-19T18:06:08.438358Z","end":"2026-03-19T18:06:08.662488Z","steps":["trace[1758703519] 'read index received'  (duration: 223.860148ms)","trace[1758703519] 'applied index is now lower than readState.Index'  (duration: 269.257µs)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:06:08.662582Z","caller":"traceutil/trace.go:171","msg":"trace[49862265] transaction","detail":"{read_only:false; response_revision:8036; number_of_response:1; }","duration":"226.399003ms","start":"2026-03-19T18:06:08.436148Z","end":"2026-03-19T18:06:08.662547Z","steps":["trace[49862265] 'process raft request'  (duration: 226.168377ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:08.662753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.1692ms","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-03-19T18:06:08.662796Z","caller":"traceutil/trace.go:171","msg":"trace[639300987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8036; }","duration":"164.263313ms","start":"2026-03-19T18:06:08.498522Z","end":"2026-03-19T18:06:08.662785Z","steps":["trace[639300987] 'agreement among raft nodes before linearized reading'  (duration: 164.098828ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:08.662791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.30143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-19T18:06:08.662854Z","caller":"traceutil/trace.go:171","msg":"trace[828111352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8036; }","duration":"108.426133ms","start":"2026-03-19T18:06:08.554416Z","end":"2026-03-19T18:06:08.662842Z","steps":["trace[828111352] 'agreement among raft nodes before linearized reading'  (duration: 108.28026ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:08.662977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.593957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ceph.189e50422e99e66b\" ","response":"range_response_count:1 size:646"}
{"level":"info","ts":"2026-03-19T18:06:08.663145Z","caller":"traceutil/trace.go:171","msg":"trace[400187704] range","detail":"{range_begin:/registry/events/openstack/ceph.189e50422e99e66b; range_end:; response_count:1; response_revision:8036; }","duration":"224.832803ms","start":"2026-03-19T18:06:08.438291Z","end":"2026-03-19T18:06:08.663124Z","steps":["trace[400187704] 'agreement among raft nodes before linearized reading'  (duration: 224.315719ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:06:08.77024Z","caller":"traceutil/trace.go:171","msg":"trace[854841206] transaction","detail":"{read_only:false; response_revision:8038; number_of_response:1; }","duration":"102.658203ms","start":"2026-03-19T18:06:08.667541Z","end":"2026-03-19T18:06:08.770199Z","steps":["trace[854841206] 'process raft request'  (duration: 102.51061ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:06:08.77029Z","caller":"traceutil/trace.go:171","msg":"trace[127363676] transaction","detail":"{read_only:false; response_revision:8037; number_of_response:1; }","duration":"104.601094ms","start":"2026-03-19T18:06:08.665609Z","end":"2026-03-19T18:06:08.77021Z","steps":["trace[127363676] 'process raft request'  (duration: 75.064837ms)","trace[127363676] 'compare'  (duration: 29.218489ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:06:52.737647Z","caller":"traceutil/trace.go:171","msg":"trace[1872721415] linearizableReadLoop","detail":"{readStateIndex:8947; appliedIndex:8946; }","duration":"205.306497ms","start":"2026-03-19T18:06:52.53232Z","end":"2026-03-19T18:06:52.737626Z","steps":["trace[1872721415] 'read index received'  (duration: 205.211975ms)","trace[1872721415] 'applied index is now lower than readState.Index'  (duration: 93.722µs)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:06:52.737739Z","caller":"traceutil/trace.go:171","msg":"trace[2005446236] transaction","detail":"{read_only:false; response_revision:8544; number_of_response:1; }","duration":"215.493131ms","start":"2026-03-19T18:06:52.522239Z","end":"2026-03-19T18:06:52.737732Z","steps":["trace[2005446236] 'process raft request'  (duration: 215.255285ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:52.737925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.592285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/glance-api\" ","response":"range_response_count:1 size:522"}
{"level":"info","ts":"2026-03-19T18:06:52.73795Z","caller":"traceutil/trace.go:171","msg":"trace[967402807] range","detail":"{range_begin:/registry/serviceaccounts/openstack/glance-api; range_end:; response_count:1; response_revision:8544; }","duration":"205.663377ms","start":"2026-03-19T18:06:52.53228Z","end":"2026-03-19T18:06:52.737943Z","steps":["trace[967402807] 'agreement among raft nodes before linearized reading'  (duration: 205.560204ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:06:52.940869Z","caller":"traceutil/trace.go:171","msg":"trace[938911248] linearizableReadLoop","detail":"{readStateIndex:8948; appliedIndex:8947; }","duration":"203.135731ms","start":"2026-03-19T18:06:52.737706Z","end":"2026-03-19T18:06:52.940841Z","steps":["trace[938911248] 'read index received'  (duration: 202.546826ms)","trace[938911248] 'applied index is now lower than readState.Index'  (duration: 587.675µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T18:06:52.94105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.281952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"}
{"level":"warn","ts":"2026-03-19T18:06:52.941072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.145585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/glance-api-669548c568-hqtkn\" ","response":"range_response_count:1 size:12781"}
{"level":"info","ts":"2026-03-19T18:06:52.941101Z","caller":"traceutil/trace.go:171","msg":"trace[1171860828] range","detail":"{range_begin:/registry/pods/openstack/glance-api-669548c568-hqtkn; range_end:; response_count:1; response_revision:8545; }","duration":"202.188296ms","start":"2026-03-19T18:06:52.738904Z","end":"2026-03-19T18:06:52.941092Z","steps":["trace[1171860828] 'agreement among raft nodes before linearized reading'  (duration: 202.095994ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:06:52.941117Z","caller":"traceutil/trace.go:171","msg":"trace[2051887803] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8545; }","duration":"374.365153ms","start":"2026-03-19T18:06:52.566724Z","end":"2026-03-19T18:06:52.94109Z","steps":["trace[2051887803] 'agreement among raft nodes before linearized reading'  (duration: 374.149208ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:52.941147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:52.566719Z","time spent":"374.420174ms","remote":"127.0.0.1:55938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "}
{"level":"warn","ts":"2026-03-19T18:06:52.941112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.581279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"}
{"level":"info","ts":"2026-03-19T18:06:52.941129Z","caller":"traceutil/trace.go:171","msg":"trace[2012381232] transaction","detail":"{read_only:false; response_revision:8545; number_of_response:1; }","duration":"408.112599ms","start":"2026-03-19T18:06:52.532932Z","end":"2026-03-19T18:06:52.941045Z","steps":["trace[2012381232] 'process raft request'  (duration: 407.340409ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:06:52.941209Z","caller":"traceutil/trace.go:171","msg":"trace[2051864305] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8545; }","duration":"374.717032ms","start":"2026-03-19T18:06:52.566469Z","end":"2026-03-19T18:06:52.941186Z","steps":["trace[2051864305] 'agreement among raft nodes before linearized reading'  (duration: 374.452805ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:52.941256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:52.566457Z","time spent":"374.786624ms","remote":"127.0.0.1:55938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"warn","ts":"2026-03-19T18:06:52.941297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:52.532904Z","time spent":"408.302353ms","remote":"127.0.0.1:55918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/events/openstack/glance-api-669548c568-hqtkn.189e50511c5fde70\" mod_revision:0 > success:<request_put:<key:\"/registry/events/openstack/glance-api-669548c568-hqtkn.189e50511c5fde70\" value_size:683 lease:8079348910898351422 >> failure:<>"}
{"level":"warn","ts":"2026-03-19T18:06:52.941326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.741865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/glance-db-init\" ","response":"range_response_count:1 size:530"}
{"level":"info","ts":"2026-03-19T18:06:52.941408Z","caller":"traceutil/trace.go:171","msg":"trace[462705186] range","detail":"{range_begin:/registry/serviceaccounts/openstack/glance-db-init; range_end:; response_count:1; response_revision:8545; }","duration":"409.030793ms","start":"2026-03-19T18:06:52.532358Z","end":"2026-03-19T18:06:52.941389Z","steps":["trace[462705186] 'agreement among raft nodes before linearized reading'  (duration: 408.611362ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:52.941452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:52.532353Z","time spent":"409.087714ms","remote":"127.0.0.1:56032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":553,"request content":"key:\"/registry/serviceaccounts/openstack/glance-db-init\" "}
{"level":"warn","ts":"2026-03-19T18:06:52.942051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.770231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2575989"}
{"level":"info","ts":"2026-03-19T18:06:52.942136Z","caller":"traceutil/trace.go:171","msg":"trace[1669205943] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8545; }","duration":"197.878173ms","start":"2026-03-19T18:06:52.744238Z","end":"2026-03-19T18:06:52.942116Z","steps":["trace[1669205943] 'agreement among raft nodes before linearized reading'  (duration: 196.854327ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:06:53.15661Z","caller":"traceutil/trace.go:171","msg":"trace[1402030155] linearizableReadLoop","detail":"{readStateIndex:8950; appliedIndex:8948; }","duration":"181.088439ms","start":"2026-03-19T18:06:52.9755Z","end":"2026-03-19T18:06:53.156589Z","steps":["trace[1402030155] 'read index received'  (duration: 101.698258ms)","trace[1402030155] 'applied index is now lower than readState.Index'  (duration: 79.389221ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:06:53.156649Z","caller":"traceutil/trace.go:171","msg":"trace[1769910569] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"209.161137ms","start":"2026-03-19T18:06:52.947452Z","end":"2026-03-19T18:06:53.156613Z","steps":["trace[1769910569] 'process raft request'  (duration: 129.678214ms)","trace[1769910569] 'compare'  (duration: 79.307929ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:06:53.156712Z","caller":"traceutil/trace.go:171","msg":"trace[501794617] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"208.085729ms","start":"2026-03-19T18:06:52.948595Z","end":"2026-03-19T18:06:53.156681Z","steps":["trace[501794617] 'process raft request'  (duration: 207.954796ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:53.156815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.306375ms","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-03-19T18:06:53.15685Z","caller":"traceutil/trace.go:171","msg":"trace[435172572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8547; }","duration":"181.408967ms","start":"2026-03-19T18:06:52.975429Z","end":"2026-03-19T18:06:53.156838Z","steps":["trace[435172572] 'agreement among raft nodes before linearized reading'  (duration: 181.273653ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:53.157175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.81225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"}
{"level":"info","ts":"2026-03-19T18:06:53.157369Z","caller":"traceutil/trace.go:171","msg":"trace[1272004557] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:8547; }","duration":"122.034477ms","start":"2026-03-19T18:06:53.035307Z","end":"2026-03-19T18:06:53.157341Z","steps":["trace[1272004557] 'agreement among raft nodes before linearized reading'  (duration: 121.654827ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:53.157175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.332402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/glance-api-669548c568-hqtkn.189e50511c5fde70\" ","response":"range_response_count:1 size:787"}
{"level":"info","ts":"2026-03-19T18:06:53.157763Z","caller":"traceutil/trace.go:171","msg":"trace[1279035069] range","detail":"{range_begin:/registry/events/openstack/glance-api-669548c568-hqtkn.189e50511c5fde70; range_end:; response_count:1; response_revision:8547; }","duration":"120.926237ms","start":"2026-03-19T18:06:53.036808Z","end":"2026-03-19T18:06:53.157734Z","steps":["trace[1279035069] 'agreement among raft nodes before linearized reading'  (duration: 120.183177ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:06:53.431159Z","caller":"traceutil/trace.go:171","msg":"trace[2069179858] linearizableReadLoop","detail":"{readStateIndex:8952; appliedIndex:8951; }","duration":"187.572937ms","start":"2026-03-19T18:06:53.243558Z","end":"2026-03-19T18:06:53.43113Z","steps":["trace[2069179858] 'read index received'  (duration: 95.656573ms)","trace[2069179858] 'applied index is now lower than readState.Index'  (duration: 91.915204ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:06:53.431186Z","caller":"traceutil/trace.go:171","msg":"trace[401578277] transaction","detail":"{read_only:false; response_revision:8549; number_of_response:1; }","duration":"269.011549ms","start":"2026-03-19T18:06:53.162146Z","end":"2026-03-19T18:06:53.431157Z","steps":["trace[401578277] 'process raft request'  (duration: 177.052233ms)","trace[401578277] 'compare'  (duration: 91.780162ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T18:06:53.431353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.457088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3522"}
{"level":"warn","ts":"2026-03-19T18:06:53.431395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.61258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"}
{"level":"info","ts":"2026-03-19T18:06:53.431426Z","caller":"traceutil/trace.go:171","msg":"trace[1437634433] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:8549; }","duration":"240.588761ms","start":"2026-03-19T18:06:53.190819Z","end":"2026-03-19T18:06:53.431408Z","steps":["trace[1437634433] 'agreement among raft nodes before linearized reading'  (duration: 240.326765ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:06:53.431433Z","caller":"traceutil/trace.go:171","msg":"trace[99589024] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8549; }","duration":"270.702632ms","start":"2026-03-19T18:06:53.160719Z","end":"2026-03-19T18:06:53.431421Z","steps":["trace[99589024] 'agreement among raft nodes before linearized reading'  (duration: 270.541228ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:06:53.436819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.600879ms","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-03-19T18:06:53.436859Z","caller":"traceutil/trace.go:171","msg":"trace[129456663] transaction","detail":"{read_only:false; response_revision:8550; number_of_response:1; }","duration":"188.783168ms","start":"2026-03-19T18:06:53.248047Z","end":"2026-03-19T18:06:53.43683Z","steps":["trace[129456663] 'process raft request'  (duration: 188.583033ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:06:53.436928Z","caller":"traceutil/trace.go:171","msg":"trace[2013109991] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:8550; }","duration":"130.65741ms","start":"2026-03-19T18:06:53.306193Z","end":"2026-03-19T18:06:53.43685Z","steps":["trace[2013109991] 'agreement among raft nodes before linearized reading'  (duration: 130.524307ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:06:53.622843Z","caller":"traceutil/trace.go:171","msg":"trace[706879213] transaction","detail":"{read_only:false; response_revision:8552; number_of_response:1; }","duration":"105.771364ms","start":"2026-03-19T18:06:53.51704Z","end":"2026-03-19T18:06:53.622812Z","steps":["trace[706879213] 'process raft request'  (duration: 104.198302ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:07:06.61675Z","caller":"traceutil/trace.go:171","msg":"trace[409926011] linearizableReadLoop","detail":"{readStateIndex:9076; appliedIndex:9074; }","duration":"138.312679ms","start":"2026-03-19T18:07:06.47841Z","end":"2026-03-19T18:07:06.616723Z","steps":["trace[409926011] 'read index received'  (duration: 109.228134ms)","trace[409926011] 'applied index is now lower than readState.Index'  (duration: 29.083475ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:07:06.616794Z","caller":"traceutil/trace.go:171","msg":"trace[930045948] transaction","detail":"{read_only:false; response_revision:8666; number_of_response:1; }","duration":"139.464728ms","start":"2026-03-19T18:07:06.477304Z","end":"2026-03-19T18:07:06.616768Z","steps":["trace[930045948] 'process raft request'  (duration: 110.436375ms)","trace[930045948] 'compare'  (duration: 28.745516ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:07:06.616823Z","caller":"traceutil/trace.go:171","msg":"trace[2123996631] transaction","detail":"{read_only:false; response_revision:8667; number_of_response:1; }","duration":"139.350415ms","start":"2026-03-19T18:07:06.477444Z","end":"2026-03-19T18:07:06.616795Z","steps":["trace[2123996631] 'process raft request'  (duration: 139.215812ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:07:06.61732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.888034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:28 size:241255"}
{"level":"info","ts":"2026-03-19T18:07:06.617387Z","caller":"traceutil/trace.go:171","msg":"trace[879335442] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:28; response_revision:8667; }","duration":"138.985316ms","start":"2026-03-19T18:07:06.478386Z","end":"2026-03-19T18:07:06.617371Z","steps":["trace[879335442] 'agreement among raft nodes before linearized reading'  (duration: 138.460902ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:07:17.259866Z","caller":"traceutil/trace.go:171","msg":"trace[42967472] linearizableReadLoop","detail":"{readStateIndex:9148; appliedIndex:9147; }","duration":"271.895574ms","start":"2026-03-19T18:07:16.987937Z","end":"2026-03-19T18:07:17.259832Z","steps":["trace[42967472] 'read index received'  (duration: 254.505502ms)","trace[42967472] 'applied index is now lower than readState.Index'  (duration: 17.388942ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:07:17.259909Z","caller":"traceutil/trace.go:171","msg":"trace[1738339200] transaction","detail":"{read_only:false; response_revision:8735; number_of_response:1; }","duration":"518.198155ms","start":"2026-03-19T18:07:16.741679Z","end":"2026-03-19T18:07:17.259877Z","steps":["trace[1738339200] 'process raft request'  (duration: 500.810434ms)","trace[1738339200] 'compare'  (duration: 16.928829ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T18:07:17.260033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:07:16.741665Z","time spent":"518.304667ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:8721 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"warn","ts":"2026-03-19T18:07:17.260076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.11176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-19T18:07:17.26012Z","caller":"traceutil/trace.go:171","msg":"trace[1032564018] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:8735; }","duration":"272.198542ms","start":"2026-03-19T18:07:16.987906Z","end":"2026-03-19T18:07:17.260104Z","steps":["trace[1032564018] 'agreement among raft nodes before linearized reading'  (duration: 272.087629ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:07:17.260254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.221513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"}
{"level":"info","ts":"2026-03-19T18:07:17.260316Z","caller":"traceutil/trace.go:171","msg":"trace[2101784410] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:8735; }","duration":"267.326546ms","start":"2026-03-19T18:07:16.992976Z","end":"2026-03-19T18:07:17.260303Z","steps":["trace[2101784410] 'agreement among raft nodes before linearized reading'  (duration: 267.154232ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:07:50.697116Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6253}
{"level":"info","ts":"2026-03-19T18:07:50.798825Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6253,"took":"99.999664ms","hash":2130473375,"current-db-size-bytes":47509504,"current-db-size":"48 MB","current-db-size-in-use-bytes":23793664,"current-db-size-in-use":"24 MB"}
{"level":"info","ts":"2026-03-19T18:07:50.79892Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2130473375,"revision":6253,"compact-revision":3018}
{"level":"warn","ts":"2026-03-19T18:07:59.087556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.016111ms","expected-duration":"100ms","prefix":"","request":"header:<ID:8079348910898353778 > lease_revoke:<id:701f9d073a89151b>","response":"size:28"}
{"level":"info","ts":"2026-03-19T18:07:59.291219Z","caller":"traceutil/trace.go:171","msg":"trace[668734336] linearizableReadLoop","detail":"{readStateIndex:9512; appliedIndex:9511; }","duration":"176.689469ms","start":"2026-03-19T18:07:59.114515Z","end":"2026-03-19T18:07:59.291205Z","steps":["trace[668734336] 'read index received'  (duration: 176.642228ms)","trace[668734336] 'applied index is now lower than readState.Index'  (duration: 46.581µs)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:07:59.291432Z","caller":"traceutil/trace.go:171","msg":"trace[1005874888] transaction","detail":"{read_only:false; response_revision:9080; number_of_response:1; }","duration":"189.267859ms","start":"2026-03-19T18:07:59.102156Z","end":"2026-03-19T18:07:59.291424Z","steps":["trace[1005874888] 'process raft request'  (duration: 188.947731ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:07:59.291585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.065999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nvs54\" ","response":"range_response_count:1 size:6531"}
{"level":"info","ts":"2026-03-19T18:07:59.291607Z","caller":"traceutil/trace.go:171","msg":"trace[872249854] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nvs54; range_end:; response_count:1; response_revision:9080; }","duration":"177.128011ms","start":"2026-03-19T18:07:59.114473Z","end":"2026-03-19T18:07:59.291601Z","steps":["trace[872249854] 'agreement among raft nodes before linearized reading'  (duration: 177.050968ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:07:59.501957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.625485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:31 size:271316"}
{"level":"info","ts":"2026-03-19T18:07:59.502035Z","caller":"traceutil/trace.go:171","msg":"trace[405597132] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:31; response_revision:9080; }","duration":"174.742878ms","start":"2026-03-19T18:07:59.327275Z","end":"2026-03-19T18:07:59.502018Z","steps":["trace[405597132] 'range keys from in-memory index tree'  (duration: 174.277757ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:07:59.716309Z","caller":"traceutil/trace.go:171","msg":"trace[1365152043] transaction","detail":"{read_only:false; response_revision:9081; number_of_response:1; }","duration":"167.401297ms","start":"2026-03-19T18:07:59.548867Z","end":"2026-03-19T18:07:59.716268Z","steps":["trace[1365152043] 'process raft request'  (duration: 167.057858ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:07:59.716437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.503479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-19T18:07:59.716482Z","caller":"traceutil/trace.go:171","msg":"trace[468260906] linearizableReadLoop","detail":"{readStateIndex:9513; appliedIndex:9512; }","duration":"162.270213ms","start":"2026-03-19T18:07:59.553902Z","end":"2026-03-19T18:07:59.716173Z","steps":["trace[468260906] 'read index received'  (duration: 162.048198ms)","trace[468260906] 'applied index is now lower than readState.Index'  (duration: 220.935µs)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:07:59.716518Z","caller":"traceutil/trace.go:171","msg":"trace[1253233935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9081; }","duration":"162.611931ms","start":"2026-03-19T18:07:59.553881Z","end":"2026-03-19T18:07:59.716493Z","steps":["trace[1253233935] 'agreement among raft nodes before linearized reading'  (duration: 162.474228ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:08:13.528628Z","caller":"traceutil/trace.go:171","msg":"trace[1730156732] transaction","detail":"{read_only:false; response_revision:9193; number_of_response:1; }","duration":"117.240035ms","start":"2026-03-19T18:08:13.411364Z","end":"2026-03-19T18:08:13.528604Z","steps":["trace[1730156732] 'process raft request'  (duration: 117.117922ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:08:41.075256Z","caller":"traceutil/trace.go:171","msg":"trace[1713498557] transaction","detail":"{read_only:false; response_revision:9458; number_of_response:1; }","duration":"125.258365ms","start":"2026-03-19T18:08:40.949966Z","end":"2026-03-19T18:08:41.075224Z","steps":["trace[1713498557] 'process raft request'  (duration: 96.808312ms)","trace[1713498557] 'compare'  (duration: 28.268168ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:08:41.075506Z","caller":"traceutil/trace.go:171","msg":"trace[217668448] transaction","detail":"{read_only:false; response_revision:9459; number_of_response:1; }","duration":"124.394763ms","start":"2026-03-19T18:08:40.951101Z","end":"2026-03-19T18:08:41.075495Z","steps":["trace[217668448] 'process raft request'  (duration: 124.077414ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:08:41.549576Z","caller":"traceutil/trace.go:171","msg":"trace[1095870397] transaction","detail":"{read_only:false; response_revision:9462; number_of_response:1; }","duration":"154.337256ms","start":"2026-03-19T18:08:41.395219Z","end":"2026-03-19T18:08:41.549556Z","steps":["trace[1095870397] 'process raft request'  (duration: 154.215443ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:08:41.733943Z","caller":"traceutil/trace.go:171","msg":"trace[1286043239] transaction","detail":"{read_only:false; response_revision:9464; number_of_response:1; }","duration":"107.613834ms","start":"2026-03-19T18:08:41.626298Z","end":"2026-03-19T18:08:41.733912Z","steps":["trace[1286043239] 'process raft request'  (duration: 107.376488ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:08:41.95149Z","caller":"traceutil/trace.go:171","msg":"trace[1988178762] linearizableReadLoop","detail":"{readStateIndex:9915; appliedIndex:9913; }","duration":"129.752572ms","start":"2026-03-19T18:08:41.821706Z","end":"2026-03-19T18:08:41.951458Z","steps":["trace[1988178762] 'read index received'  (duration: 15.840713ms)","trace[1988178762] 'applied index is now lower than readState.Index'  (duration: 113.906499ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:08:41.951514Z","caller":"traceutil/trace.go:171","msg":"trace[1106433966] transaction","detail":"{read_only:false; response_revision:9466; number_of_response:1; }","duration":"204.550569ms","start":"2026-03-19T18:08:41.746927Z","end":"2026-03-19T18:08:41.951477Z","steps":["trace[1106433966] 'process raft request'  (duration: 192.614237ms)","trace[1106433966] 'compare'  (duration: 11.701846ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T18:08:41.951799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.08158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nvs54\" ","response":"range_response_count:1 size:6531"}
{"level":"info","ts":"2026-03-19T18:08:41.951832Z","caller":"traceutil/trace.go:171","msg":"trace[1486245942] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nvs54; range_end:; response_count:1; response_revision:9466; }","duration":"130.168043ms","start":"2026-03-19T18:08:41.821654Z","end":"2026-03-19T18:08:41.951822Z","steps":["trace[1486245942] 'agreement among raft nodes before linearized reading'  (duration: 129.912926ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:08:51.767365Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"db79120667ce701f","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-03-19T18:08:51.773266Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-03-19T18:08:51.773428Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"info","ts":"2026-03-19T18:08:54.520195Z","caller":"traceutil/trace.go:171","msg":"trace[1377351008] transaction","detail":"{read_only:false; response_revision:9569; number_of_response:1; }","duration":"142.233299ms","start":"2026-03-19T18:08:54.377929Z","end":"2026-03-19T18:08:54.520163Z","steps":["trace[1377351008] 'process raft request'  (duration: 142.064075ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:09:12.470466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.524129ms","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-03-19T18:09:12.47058Z","caller":"traceutil/trace.go:171","msg":"trace[1211714111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9718; }","duration":"134.682013ms","start":"2026-03-19T18:09:12.335867Z","end":"2026-03-19T18:09:12.470549Z","steps":["trace[1211714111] 'range keys from in-memory index tree'  (duration: 134.331842ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:12:50.703525Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9012}
{"level":"info","ts":"2026-03-19T18:12:50.789065Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9012,"took":"83.441164ms","hash":3865859087,"current-db-size-bytes":47509504,"current-db-size":"48 MB","current-db-size-in-use-bytes":21913600,"current-db-size-in-use":"22 MB"}
{"level":"info","ts":"2026-03-19T18:12:50.789139Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3865859087,"revision":9012,"compact-revision":6253}
{"level":"warn","ts":"2026-03-19T18:14:18.671793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.808133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"}
{"level":"info","ts":"2026-03-19T18:14:18.671901Z","caller":"traceutil/trace.go:171","msg":"trace[1491153481] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11738; }","duration":"108.956277ms","start":"2026-03-19T18:14:18.562919Z","end":"2026-03-19T18:14:18.671876Z","steps":["trace[1491153481] 'range keys from in-memory index tree'  (duration: 108.591888ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:14:18.671895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.830203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"}
{"level":"info","ts":"2026-03-19T18:14:18.671986Z","caller":"traceutil/trace.go:171","msg":"trace[740624340] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:11738; }","duration":"109.002428ms","start":"2026-03-19T18:14:18.562961Z","end":"2026-03-19T18:14:18.671963Z","steps":["trace[740624340] 'range keys from in-memory index tree'  (duration: 108.588127ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:14:18.67202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.68584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nvs54\" ","response":"range_response_count:1 size:6531"}
{"level":"warn","ts":"2026-03-19T18:14:18.672021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.361009ms","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-03-19T18:14:18.67208Z","caller":"traceutil/trace.go:171","msg":"trace[1252215720] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nvs54; range_end:; response_count:1; response_revision:11738; }","duration":"340.789342ms","start":"2026-03-19T18:14:18.331278Z","end":"2026-03-19T18:14:18.672068Z","steps":["trace[1252215720] 'range keys from in-memory index tree'  (duration: 340.466224ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:14:18.67209Z","caller":"traceutil/trace.go:171","msg":"trace[1408413586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11738; }","duration":"269.484562ms","start":"2026-03-19T18:14:18.402591Z","end":"2026-03-19T18:14:18.672075Z","steps":["trace[1408413586] 'range keys from in-memory index tree'  (duration: 269.114232ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:14:18.672105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:14:18.331257Z","time spent":"340.843744ms","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-nvs54\" "}
{"level":"info","ts":"2026-03-19T18:14:18.935924Z","caller":"traceutil/trace.go:171","msg":"trace[1188698423] linearizableReadLoop","detail":"{readStateIndex:12334; appliedIndex:12333; }","duration":"158.155284ms","start":"2026-03-19T18:14:18.77774Z","end":"2026-03-19T18:14:18.935895Z","steps":["trace[1188698423] 'read index received'  (duration: 157.823535ms)","trace[1188698423] 'applied index is now lower than readState.Index'  (duration: 330.698µs)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:14:18.935983Z","caller":"traceutil/trace.go:171","msg":"trace[1744160458] transaction","detail":"{read_only:false; response_revision:11743; number_of_response:1; }","duration":"251.58895ms","start":"2026-03-19T18:14:18.684351Z","end":"2026-03-19T18:14:18.935939Z","steps":["trace[1744160458] 'process raft request'  (duration: 251.299591ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:14:18.936293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.520753ms","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-03-19T18:14:18.936373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.041225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"info","ts":"2026-03-19T18:14:18.937208Z","caller":"traceutil/trace.go:171","msg":"trace[1229341876] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11743; }","duration":"152.887267ms","start":"2026-03-19T18:14:18.784277Z","end":"2026-03-19T18:14:18.937164Z","steps":["trace[1229341876] 'agreement among raft nodes before linearized reading'  (duration: 151.807409ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:14:18.936412Z","caller":"traceutil/trace.go:171","msg":"trace[2091036339] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11743; }","duration":"158.665347ms","start":"2026-03-19T18:14:18.777709Z","end":"2026-03-19T18:14:18.936374Z","steps":["trace[2091036339] 'agreement among raft nodes before linearized reading'  (duration: 158.348539ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:14:18.936488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.669918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:11 size:90006"}
{"level":"info","ts":"2026-03-19T18:14:18.937468Z","caller":"traceutil/trace.go:171","msg":"trace[248621850] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:11; response_revision:11743; }","duration":"139.691586ms","start":"2026-03-19T18:14:18.797751Z","end":"2026-03-19T18:14:18.937443Z","steps":["trace[248621850] 'agreement among raft nodes before linearized reading'  (duration: 138.381371ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T18:14:57.429798Z","caller":"traceutil/trace.go:171","msg":"trace[659352070] linearizableReadLoop","detail":"{readStateIndex:12581; appliedIndex:12580; }","duration":"240.96661ms","start":"2026-03-19T18:14:57.188804Z","end":"2026-03-19T18:14:57.429771Z","steps":["trace[659352070] 'read index received'  (duration: 240.748385ms)","trace[659352070] 'applied index is now lower than readState.Index'  (duration: 217.035µs)"],"step_count":2}
{"level":"info","ts":"2026-03-19T18:14:57.429977Z","caller":"traceutil/trace.go:171","msg":"trace[133884632] transaction","detail":"{read_only:false; response_revision:11973; number_of_response:1; }","duration":"261.217196ms","start":"2026-03-19T18:14:57.168612Z","end":"2026-03-19T18:14:57.42983Z","steps":["trace[133884632] 'process raft request'  (duration: 260.99994ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:14:57.430128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.366918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"}
{"level":"info","ts":"2026-03-19T18:14:57.430204Z","caller":"traceutil/trace.go:171","msg":"trace[1234097924] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11973; }","duration":"137.492271ms","start":"2026-03-19T18:14:57.292692Z","end":"2026-03-19T18:14:57.430185Z","steps":["trace[1234097924] 'agreement among raft nodes before linearized reading'  (duration: 137.267656ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:14:57.43033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.495114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:1226"}
{"level":"info","ts":"2026-03-19T18:14:57.430386Z","caller":"traceutil/trace.go:171","msg":"trace[449003295] range","detail":"{range_begin:/registry/clusterrolebindings/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:11973; }","duration":"241.605057ms","start":"2026-03-19T18:14:57.188766Z","end":"2026-03-19T18:14:57.430371Z","steps":["trace[449003295] 'agreement among raft nodes before linearized reading'  (duration: 241.143265ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:16:05.007121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.775999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/glance-ks-endpoints\" ","response":"range_response_count:1 size:540"}
{"level":"info","ts":"2026-03-19T18:16:05.007423Z","caller":"traceutil/trace.go:171","msg":"trace[2040041636] range","detail":"{range_begin:/registry/serviceaccounts/openstack/glance-ks-endpoints; range_end:; response_count:1; response_revision:12381; }","duration":"322.099628ms","start":"2026-03-19T18:16:04.685303Z","end":"2026-03-19T18:16:05.007402Z","steps":["trace[2040041636] 'range keys from in-memory index tree'  (duration: 321.487392ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:16:05.00747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:16:04.685241Z","time spent":"322.21835ms","remote":"127.0.0.1:56032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":563,"request content":"key:\"/registry/serviceaccounts/openstack/glance-ks-endpoints\" "}
{"level":"warn","ts":"2026-03-19T18:16:05.007605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.935897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rook-ceph-rgw-ceph-a-67fd8975c6-9d5r7.189e50644ddaecd6\\000\" range_end:\"/registry/events/openstack0\" limit:1000 revision:12381 ","response":"range_response_count:50 size:38208"}
{"level":"info","ts":"2026-03-19T18:16:05.007692Z","caller":"traceutil/trace.go:171","msg":"trace[1093507690] range","detail":"{range_begin:/registry/events/openstack/rook-ceph-rgw-ceph-a-67fd8975c6-9d5r7.189e50644ddaecd6\u0000; range_end:/registry/events/openstack0; response_count:50; response_revision:12381; }","duration":"351.06961ms","start":"2026-03-19T18:16:04.656603Z","end":"2026-03-19T18:16:05.007672Z","steps":["trace[1093507690] 'range keys from in-memory index tree'  (duration: 350.621159ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T18:16:05.007738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:16:04.656588Z","time spent":"351.136352ms","remote":"127.0.0.1:55918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":119,"response count":50,"response size":38231,"request content":"key:\"/registry/events/openstack/rook-ceph-rgw-ceph-a-67fd8975c6-9d5r7.189e50644ddaecd6\\000\" range_end:\"/registry/events/openstack0\" limit:1000 revision:12381 "}