{"level":"warn","ts":"2026-03-10T18:06:53.38414Z","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-10T18:06:53.384227Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.40: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.40:2380","--initial-cluster=instance=https://199.19.213.40:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.40:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.40: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-10T18:06:53.384299Z","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-10T18:06:53.384315Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.40:2380"]}
{"level":"info","ts":"2026-03-10T18:06:53.384336Z","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-10T18:06:53.38504Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.40:2379"]}
{"level":"info","ts":"2026-03-10T18:06:53.385152Z","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.40:2380"],"listen-peer-urls":["https://199.19.213.40:2380"],"advertise-client-urls":["https://199.19.213.40:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.40:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.40: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-10T18:06:53.392089Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.700855ms"}
{"level":"info","ts":"2026-03-10T18:06:53.401427Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1082227b3dfb8db7","cluster-id":"1c36bbf7868e18b9"}
{"level":"info","ts":"2026-03-10T18:06:53.401559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1082227b3dfb8db7 switched to configuration voters=()"}
{"level":"info","ts":"2026-03-10T18:06:53.401623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1082227b3dfb8db7 became follower at term 0"}
{"level":"info","ts":"2026-03-10T18:06:53.401691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1082227b3dfb8db7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-03-10T18:06:53.401748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1082227b3dfb8db7 became follower at term 1"}
{"level":"info","ts":"2026-03-10T18:06:53.401843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1082227b3dfb8db7 switched to configuration voters=(1189551164295450039)"}
{"level":"warn","ts":"2026-03-10T18:06:53.408055Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-03-10T18:06:53.409361Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-03-10T18:06:53.411018Z","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-10T18:06:53.412953Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1082227b3dfb8db7","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-03-10T18:06:53.413701Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1082227b3dfb8db7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-03-10T18:06:53.413809Z","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-10T18:06:53.414784Z","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-10T18:06:53.414826Z","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-10T18:06:53.415033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1082227b3dfb8db7 switched to configuration voters=(1189551164295450039)"}
{"level":"info","ts":"2026-03-10T18:06:53.415299Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1c36bbf7868e18b9","local-member-id":"1082227b3dfb8db7","added-peer-id":"1082227b3dfb8db7","added-peer-peer-urls":["https://199.19.213.40:2380"]}
{"level":"info","ts":"2026-03-10T18:06:53.418699Z","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-10T18:06:53.418846Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.40:2380"}
{"level":"info","ts":"2026-03-10T18:06:53.418923Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.40:2380"}
{"level":"info","ts":"2026-03-10T18:06:53.419148Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1082227b3dfb8db7","initial-advertise-peer-urls":["https://199.19.213.40:2380"],"listen-peer-urls":["https://199.19.213.40:2380"],"advertise-client-urls":["https://199.19.213.40:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.40:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-03-10T18:06:53.419223Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-03-10T18:06:53.502392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1082227b3dfb8db7 is starting a new election at term 1"}
{"level":"info","ts":"2026-03-10T18:06:53.502478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1082227b3dfb8db7 became pre-candidate at term 1"}
{"level":"info","ts":"2026-03-10T18:06:53.502502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1082227b3dfb8db7 received MsgPreVoteResp from 1082227b3dfb8db7 at term 1"}
{"level":"info","ts":"2026-03-10T18:06:53.502523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1082227b3dfb8db7 became candidate at term 2"}
{"level":"info","ts":"2026-03-10T18:06:53.502536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1082227b3dfb8db7 received MsgVoteResp from 1082227b3dfb8db7 at term 2"}
{"level":"info","ts":"2026-03-10T18:06:53.502553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1082227b3dfb8db7 became leader at term 2"}
{"level":"info","ts":"2026-03-10T18:06:53.502566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1082227b3dfb8db7 elected leader 1082227b3dfb8db7 at term 2"}
{"level":"info","ts":"2026-03-10T18:06:53.503659Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-10T18:06:53.503885Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-10T18:06:53.503963Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-10T18:06:53.504276Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-03-10T18:06:53.504337Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-03-10T18:06:53.504712Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1c36bbf7868e18b9","local-member-id":"1082227b3dfb8db7","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-10T18:06:53.504917Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-10T18:06:53.505025Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-10T18:06:53.50693Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.40:2379"}
{"level":"info","ts":"2026-03-10T18:06:53.507357Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-03-10T18:06:53.507685Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1082227b3dfb8db7","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.40:2379]}","request-path":"/0/members/1082227b3dfb8db7/attributes","cluster-id":"1c36bbf7868e18b9","publish-timeout":"7s"}
{"level":"info","ts":"2026-03-10T18:07:31.230482Z","caller":"traceutil/trace.go:171","msg":"trace[1375986469] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"157.563328ms","start":"2026-03-10T18:07:31.072901Z","end":"2026-03-10T18:07:31.230464Z","steps":["trace[1375986469] 'process raft request'  (duration: 157.424775ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:07:31.8143Z","caller":"traceutil/trace.go:171","msg":"trace[1283298566] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"188.13029ms","start":"2026-03-10T18:07:31.626142Z","end":"2026-03-10T18:07:31.814272Z","steps":["trace[1283298566] 'process raft request'  (duration: 185.688318ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:08:43.006388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.720084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-810a778f-f572-44c0-b273-62f23563f880\" ","response":"range_response_count:1 size:3626"}
{"level":"info","ts":"2026-03-10T18:08:43.006528Z","caller":"traceutil/trace.go:171","msg":"trace[1562950922] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-810a778f-f572-44c0-b273-62f23563f880; range_end:; response_count:1; response_revision:1603; }","duration":"219.945689ms","start":"2026-03-10T18:08:42.78656Z","end":"2026-03-10T18:08:43.006506Z","steps":["trace[1562950922] 'range keys from in-memory index tree'  (duration: 219.561881ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:09:34.38407Z","caller":"traceutil/trace.go:171","msg":"trace[1467051936] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"108.912272ms","start":"2026-03-10T18:09:34.275133Z","end":"2026-03-10T18:09:34.384045Z","steps":["trace[1467051936] 'process raft request'  (duration: 108.757838ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:09:34.655217Z","caller":"traceutil/trace.go:171","msg":"trace[2099888874] linearizableReadLoop","detail":"{readStateIndex:1984; appliedIndex:1981; }","duration":"304.383366ms","start":"2026-03-10T18:09:34.350805Z","end":"2026-03-10T18:09:34.655188Z","steps":["trace[2099888874] 'read index received'  (duration: 33.092096ms)","trace[2099888874] 'applied index is now lower than readState.Index'  (duration: 271.28965ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:09:34.655238Z","caller":"traceutil/trace.go:171","msg":"trace[469825267] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"378.550367ms","start":"2026-03-10T18:09:34.276648Z","end":"2026-03-10T18:09:34.655199Z","steps":["trace[469825267] 'process raft request'  (duration: 350.722902ms)","trace[469825267] 'compare'  (duration: 27.621011ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:09:34.655388Z","caller":"traceutil/trace.go:171","msg":"trace[731384620] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"322.242882ms","start":"2026-03-10T18:09:34.333108Z","end":"2026-03-10T18:09:34.655351Z","steps":["trace[731384620] 'process raft request'  (duration: 322.021128ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:09:34.655476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.963516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"warn","ts":"2026-03-10T18:09:34.655495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.670442ms","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-10T18:09:34.655546Z","caller":"traceutil/trace.go:171","msg":"trace[277300109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1906; }","duration":"140.067028ms","start":"2026-03-10T18:09:34.515462Z","end":"2026-03-10T18:09:34.655529Z","steps":["trace[277300109] 'agreement among raft nodes before linearized reading'  (duration: 139.908305ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:09:34.655556Z","caller":"traceutil/trace.go:171","msg":"trace[2007049257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1906; }","duration":"304.781694ms","start":"2026-03-10T18:09:34.350757Z","end":"2026-03-10T18:09:34.655539Z","steps":["trace[2007049257] 'agreement among raft nodes before linearized reading'  (duration: 304.58608ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:09:34.655603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:09:34.350737Z","time spent":"304.855235ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-03-10T18:09:34.656032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:09:34.276629Z","time spent":"378.69713ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:1896 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-03-10T18:09:34.656108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:09:34.333085Z","time spent":"322.376715ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:1897 > 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-10T18:10:57.235725Z","caller":"traceutil/trace.go:171","msg":"trace[472312216] transaction","detail":"{read_only:false; response_revision:2504; number_of_response:1; }","duration":"246.44634ms","start":"2026-03-10T18:10:56.989259Z","end":"2026-03-10T18:10:57.235705Z","steps":["trace[472312216] 'process raft request'  (duration: 246.401309ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:10:57.235803Z","caller":"traceutil/trace.go:171","msg":"trace[1280595449] transaction","detail":"{read_only:false; response_revision:2503; number_of_response:1; }","duration":"248.198437ms","start":"2026-03-10T18:10:56.987565Z","end":"2026-03-10T18:10:57.235763Z","steps":["trace[1280595449] 'process raft request'  (duration: 247.769938ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:10:57.236713Z","caller":"traceutil/trace.go:171","msg":"trace[1171921226] linearizableReadLoop","detail":"{readStateIndex:2608; appliedIndex:2605; }","duration":"202.84672ms","start":"2026-03-10T18:10:57.033851Z","end":"2026-03-10T18:10:57.236697Z","steps":["trace[1171921226] 'read index received'  (duration: 201.740667ms)","trace[1171921226] 'applied index is now lower than readState.Index'  (duration: 1.104993ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:10:57.236723Z","caller":"traceutil/trace.go:171","msg":"trace[1346672089] transaction","detail":"{read_only:false; response_revision:2505; number_of_response:1; }","duration":"203.071954ms","start":"2026-03-10T18:10:57.033635Z","end":"2026-03-10T18:10:57.236707Z","steps":["trace[1346672089] 'process raft request'  (duration: 203.004933ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:10:57.236833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.967312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-10T18:10:57.236899Z","caller":"traceutil/trace.go:171","msg":"trace[1073397966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2505; }","duration":"203.072334ms","start":"2026-03-10T18:10:57.033817Z","end":"2026-03-10T18:10:57.236889Z","steps":["trace[1073397966] 'agreement among raft nodes before linearized reading'  (duration: 202.953301ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:13:32.168203Z","caller":"traceutil/trace.go:171","msg":"trace[1252531388] linearizableReadLoop","detail":"{readStateIndex:3588; appliedIndex:3587; }","duration":"141.598289ms","start":"2026-03-10T18:13:32.026573Z","end":"2026-03-10T18:13:32.168172Z","steps":["trace[1252531388] 'read index received'  (duration: 141.463296ms)","trace[1252531388] 'applied index is now lower than readState.Index'  (duration: 134.063µs)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:13:32.168268Z","caller":"traceutil/trace.go:171","msg":"trace[1688064358] transaction","detail":"{read_only:false; response_revision:3447; number_of_response:1; }","duration":"145.088054ms","start":"2026-03-10T18:13:32.023122Z","end":"2026-03-10T18:13:32.16821Z","steps":["trace[1688064358] 'process raft request'  (duration: 144.859589ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:13:32.16846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.860014ms","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-10T18:13:32.168494Z","caller":"traceutil/trace.go:171","msg":"trace[1753467639] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3447; }","duration":"141.943457ms","start":"2026-03-10T18:13:32.026543Z","end":"2026-03-10T18:13:32.168487Z","steps":["trace[1753467639] 'agreement among raft nodes before linearized reading'  (duration: 141.777913ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:13:32.21368Z","caller":"traceutil/trace.go:171","msg":"trace[1841202245] transaction","detail":"{read_only:false; response_revision:3448; number_of_response:1; }","duration":"186.80311ms","start":"2026-03-10T18:13:32.026795Z","end":"2026-03-10T18:13:32.213598Z","steps":["trace[1841202245] 'process raft request'  (duration: 186.527784ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:13:32.213768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.42594ms","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-10T18:13:32.213882Z","caller":"traceutil/trace.go:171","msg":"trace[409326084] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3449; }","duration":"185.572653ms","start":"2026-03-10T18:13:32.02828Z","end":"2026-03-10T18:13:32.213853Z","steps":["trace[409326084] 'agreement among raft nodes before linearized reading'  (duration: 185.266617ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:13:32.214029Z","caller":"traceutil/trace.go:171","msg":"trace[123175321] transaction","detail":"{read_only:false; response_revision:3449; number_of_response:1; }","duration":"186.369609ms","start":"2026-03-10T18:13:32.02763Z","end":"2026-03-10T18:13:32.213999Z","steps":["trace[123175321] 'process raft request'  (duration: 185.865159ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:15:00.524689Z","caller":"traceutil/trace.go:171","msg":"trace[996077888] linearizableReadLoop","detail":"{readStateIndex:4851; appliedIndex:4849; }","duration":"106.847462ms","start":"2026-03-10T18:15:00.417812Z","end":"2026-03-10T18:15:00.524659Z","steps":["trace[996077888] 'read index received'  (duration: 25.835687ms)","trace[996077888] 'applied index is now lower than readState.Index'  (duration: 81.011035ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:15:00.524758Z","caller":"traceutil/trace.go:171","msg":"trace[1353180114] transaction","detail":"{read_only:false; response_revision:4662; number_of_response:1; }","duration":"122.82615ms","start":"2026-03-10T18:15:00.401896Z","end":"2026-03-10T18:15:00.524722Z","steps":["trace[1353180114] 'process raft request'  (duration: 103.46601ms)","trace[1353180114] 'compare'  (duration: 19.182716ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:15:00.524888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.062846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"}
{"level":"info","ts":"2026-03-10T18:15:00.524921Z","caller":"traceutil/trace.go:171","msg":"trace[55520445] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4662; }","duration":"107.120928ms","start":"2026-03-10T18:15:00.417789Z","end":"2026-03-10T18:15:00.52491Z","steps":["trace[55520445] 'agreement among raft nodes before linearized reading'  (duration: 106.973504ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:15:07.340529Z","caller":"traceutil/trace.go:171","msg":"trace[340786480] transaction","detail":"{read_only:false; response_revision:4786; number_of_response:1; }","duration":"169.927477ms","start":"2026-03-10T18:15:07.170544Z","end":"2026-03-10T18:15:07.340472Z","steps":["trace[340786480] 'process raft request'  (duration: 126.929217ms)","trace[340786480] 'compare'  (duration: 42.682614ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:15:07.341036Z","caller":"traceutil/trace.go:171","msg":"trace[1789319192] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"134.247003ms","start":"2026-03-10T18:15:07.206721Z","end":"2026-03-10T18:15:07.340968Z","steps":["trace[1789319192] 'process raft request'  (duration: 133.658959ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:15:15.955199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.057794ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10211803135608620497 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:4849 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-10T18:15:15.955299Z","caller":"traceutil/trace.go:171","msg":"trace[708479302] linearizableReadLoop","detail":"{readStateIndex:5124; appliedIndex:5122; }","duration":"209.048996ms","start":"2026-03-10T18:15:15.746234Z","end":"2026-03-10T18:15:15.955283Z","steps":["trace[708479302] 'read index received'  (duration: 167.634µs)","trace[708479302] 'applied index is now lower than readState.Index'  (duration: 208.880172ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:15:15.955687Z","caller":"traceutil/trace.go:171","msg":"trace[1491474067] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"239.201195ms","start":"2026-03-10T18:15:15.716446Z","end":"2026-03-10T18:15:15.955648Z","steps":["trace[1491474067] 'process raft request'  (duration: 83.476538ms)","trace[1491474067] 'compare'  (duration: 154.9396ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:15:15.955928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.678929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a31b9891-e7ff-4b3e-9fde-f7e509f9a7ea\" ","response":"range_response_count:1 size:3645"}
{"level":"warn","ts":"2026-03-10T18:15:15.956023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.849499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lg9qw\" ","response":"range_response_count:1 size:6447"}
{"level":"info","ts":"2026-03-10T18:15:15.956117Z","caller":"traceutil/trace.go:171","msg":"trace[1989255995] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lg9qw; range_end:; response_count:1; response_revision:4928; }","duration":"181.013362ms","start":"2026-03-10T18:15:15.775085Z","end":"2026-03-10T18:15:15.956098Z","steps":["trace[1989255995] 'agreement among raft nodes before linearized reading'  (duration: 180.764367ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:15:15.956119Z","caller":"traceutil/trace.go:171","msg":"trace[1297225702] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a31b9891-e7ff-4b3e-9fde-f7e509f9a7ea; range_end:; response_count:1; response_revision:4928; }","duration":"209.901224ms","start":"2026-03-10T18:15:15.746159Z","end":"2026-03-10T18:15:15.95606Z","steps":["trace[1297225702] 'agreement among raft nodes before linearized reading'  (duration: 209.557816ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:15:30.294928Z","caller":"traceutil/trace.go:171","msg":"trace[1901835355] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"311.656067ms","start":"2026-03-10T18:15:29.983236Z","end":"2026-03-10T18:15:30.294892Z","steps":["trace[1901835355] 'process raft request'  (duration: 311.479944ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:15:30.295126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:15:29.98322Z","time spent":"311.801141ms","remote":"127.0.0.1:58026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19837,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/minions/instance\" mod_revision:4636 > success:<request_put:<key:\"/registry/minions/instance\" value_size:19801 >> failure:<request_range:<key:\"/registry/minions/instance\" > >"}
{"level":"info","ts":"2026-03-10T18:15:30.314789Z","caller":"traceutil/trace.go:171","msg":"trace[651810474] linearizableReadLoop","detail":"{readStateIndex:5330; appliedIndex:5328; }","duration":"288.642602ms","start":"2026-03-10T18:15:30.026122Z","end":"2026-03-10T18:15:30.314765Z","steps":["trace[651810474] 'read index received'  (duration: 268.591406ms)","trace[651810474] 'applied index is now lower than readState.Index'  (duration: 20.050076ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:15:30.314962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.832925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lg9qw\" ","response":"range_response_count:1 size:6447"}
{"level":"info","ts":"2026-03-10T18:15:30.314988Z","caller":"traceutil/trace.go:171","msg":"trace[1298609196] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lg9qw; range_end:; response_count:1; response_revision:5131; }","duration":"288.928377ms","start":"2026-03-10T18:15:30.026053Z","end":"2026-03-10T18:15:30.314981Z","steps":["trace[1298609196] 'agreement among raft nodes before linearized reading'  (duration: 288.791594ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:15:30.314965Z","caller":"traceutil/trace.go:171","msg":"trace[783842547] transaction","detail":"{read_only:false; response_revision:5129; number_of_response:1; }","duration":"312.023626ms","start":"2026-03-10T18:15:30.002889Z","end":"2026-03-10T18:15:30.314912Z","steps":["trace[783842547] 'process raft request'  (duration: 311.712548ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:15:30.315184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:15:30.002849Z","time spent":"312.22444ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:5099 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"info","ts":"2026-03-10T18:16:53.997015Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2789}
{"level":"info","ts":"2026-03-10T18:16:54.059072Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2789,"took":"59.893055ms","hash":3430321198,"current-db-size-bytes":41156608,"current-db-size":"41 MB","current-db-size-in-use-bytes":30703616,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-03-10T18:16:54.059132Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3430321198,"revision":2789,"compact-revision":-1}
{"level":"info","ts":"2026-03-10T18:21:49.189128Z","caller":"traceutil/trace.go:171","msg":"trace[1306759611] linearizableReadLoop","detail":"{readStateIndex:8025; appliedIndex:8024; }","duration":"216.143524ms","start":"2026-03-10T18:21:48.972948Z","end":"2026-03-10T18:21:49.189091Z","steps":["trace[1306759611] 'read index received'  (duration: 215.926299ms)","trace[1306759611] 'applied index is now lower than readState.Index'  (duration: 215.505µs)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:21:49.189184Z","caller":"traceutil/trace.go:171","msg":"trace[737120967] transaction","detail":"{read_only:false; response_revision:7734; number_of_response:1; }","duration":"244.796091ms","start":"2026-03-10T18:21:48.94434Z","end":"2026-03-10T18:21:49.189136Z","steps":["trace[737120967] 'process raft request'  (duration: 244.516895ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:21:49.189356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.383688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-10T18:21:49.189424Z","caller":"traceutil/trace.go:171","msg":"trace[801314705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7734; }","duration":"216.499611ms","start":"2026-03-10T18:21:48.972905Z","end":"2026-03-10T18:21:49.189405Z","steps":["trace[801314705] 'agreement among raft nodes before linearized reading'  (duration: 216.358458ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:21:49.189631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.089445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"warn","ts":"2026-03-10T18:21:49.189645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.423333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-03-10T18:21:49.189676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.947753ms","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-10T18:21:49.189727Z","caller":"traceutil/trace.go:171","msg":"trace[1691054005] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7734; }","duration":"194.278879ms","start":"2026-03-10T18:21:48.995432Z","end":"2026-03-10T18:21:49.189711Z","steps":["trace[1691054005] 'agreement among raft nodes before linearized reading'  (duration: 193.982052ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:21:49.189756Z","caller":"traceutil/trace.go:171","msg":"trace[2125135529] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:7734; }","duration":"120.624307ms","start":"2026-03-10T18:21:49.069109Z","end":"2026-03-10T18:21:49.189733Z","steps":["trace[2125135529] 'agreement among raft nodes before linearized reading'  (duration: 120.366592ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:21:49.189782Z","caller":"traceutil/trace.go:171","msg":"trace[1655703700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7734; }","duration":"195.112267ms","start":"2026-03-10T18:21:48.99465Z","end":"2026-03-10T18:21:49.189762Z","steps":["trace[1655703700] 'agreement among raft nodes before linearized reading'  (duration: 194.77178ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:21:49.18968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.737117ms","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-10T18:21:49.189838Z","caller":"traceutil/trace.go:171","msg":"trace[1324445065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7734; }","duration":"200.946781ms","start":"2026-03-10T18:21:48.988876Z","end":"2026-03-10T18:21:49.189823Z","steps":["trace[1324445065] 'agreement among raft nodes before linearized reading'  (duration: 200.540602ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:21:49.336374Z","caller":"traceutil/trace.go:171","msg":"trace[1790424861] transaction","detail":"{read_only:false; response_revision:7735; number_of_response:1; }","duration":"141.045949ms","start":"2026-03-10T18:21:49.195282Z","end":"2026-03-10T18:21:49.336328Z","steps":["trace[1790424861] 'process raft request'  (duration: 127.706317ms)","trace[1790424861] 'compare'  (duration: 13.148298ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:21:49.347158Z","caller":"traceutil/trace.go:171","msg":"trace[1383651553] transaction","detail":"{read_only:false; response_revision:7736; number_of_response:1; }","duration":"151.480442ms","start":"2026-03-10T18:21:49.195644Z","end":"2026-03-10T18:21:49.347124Z","steps":["trace[1383651553] 'process raft request'  (duration: 151.131164ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:21:49.347611Z","caller":"traceutil/trace.go:171","msg":"trace[1745682768] transaction","detail":"{read_only:false; response_revision:7737; number_of_response:1; }","duration":"149.299825ms","start":"2026-03-10T18:21:49.198283Z","end":"2026-03-10T18:21:49.347583Z","steps":["trace[1745682768] 'process raft request'  (duration: 148.684682ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:21:50.015409Z","caller":"traceutil/trace.go:171","msg":"trace[148047332] transaction","detail":"{read_only:false; response_revision:7738; number_of_response:1; }","duration":"169.461784ms","start":"2026-03-10T18:21:49.845914Z","end":"2026-03-10T18:21:50.015376Z","steps":["trace[148047332] 'process raft request'  (duration: 169.234909ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:21:50.249899Z","caller":"traceutil/trace.go:171","msg":"trace[826607321] linearizableReadLoop","detail":"{readStateIndex:8031; appliedIndex:8028; }","duration":"276.146884ms","start":"2026-03-10T18:21:49.973725Z","end":"2026-03-10T18:21:50.249872Z","steps":["trace[826607321] 'read index received'  (duration: 41.387017ms)","trace[826607321] 'applied index is now lower than readState.Index'  (duration: 234.758357ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:21:50.249954Z","caller":"traceutil/trace.go:171","msg":"trace[941196864] transaction","detail":"{read_only:false; response_revision:7739; number_of_response:1; }","duration":"396.307481ms","start":"2026-03-10T18:21:49.853597Z","end":"2026-03-10T18:21:50.249905Z","steps":["trace[941196864] 'process raft request'  (duration: 315.930808ms)","trace[941196864] 'compare'  (duration: 80.149729ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:21:50.25004Z","caller":"traceutil/trace.go:171","msg":"trace[795796770] transaction","detail":"{read_only:false; response_revision:7740; number_of_response:1; }","duration":"391.750415ms","start":"2026-03-10T18:21:49.858236Z","end":"2026-03-10T18:21:50.249987Z","steps":["trace[795796770] 'process raft request'  (duration: 391.593832ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:21:50.250157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.404929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-10T18:21:50.250283Z","caller":"traceutil/trace.go:171","msg":"trace[1785263495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7740; }","duration":"276.577173ms","start":"2026-03-10T18:21:49.973676Z","end":"2026-03-10T18:21:50.250253Z","steps":["trace[1785263495] 'agreement among raft nodes before linearized reading'  (duration: 276.386389ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:21:50.250286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:21:49.853582Z","time spent":"396.528417ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:7731 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"warn","ts":"2026-03-10T18:21:50.250304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:21:49.858228Z","time spent":"391.948819ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:7732 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-03-10T18:21:50.25036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.102676ms","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-10T18:21:50.250458Z","caller":"traceutil/trace.go:171","msg":"trace[1347785861] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7740; }","duration":"269.245669ms","start":"2026-03-10T18:21:49.981189Z","end":"2026-03-10T18:21:50.250435Z","steps":["trace[1347785861] 'agreement among raft nodes before linearized reading'  (duration: 268.927062ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:21:51.500244Z","caller":"traceutil/trace.go:171","msg":"trace[1365929864] transaction","detail":"{read_only:false; response_revision:7745; number_of_response:1; }","duration":"113.545488ms","start":"2026-03-10T18:21:51.386672Z","end":"2026-03-10T18:21:51.500217Z","steps":["trace[1365929864] 'process raft request'  (duration: 113.480316ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:21:51.500312Z","caller":"traceutil/trace.go:171","msg":"trace[1563454621] transaction","detail":"{read_only:false; response_revision:7744; number_of_response:1; }","duration":"113.749862ms","start":"2026-03-10T18:21:51.386519Z","end":"2026-03-10T18:21:51.500269Z","steps":["trace[1563454621] 'process raft request'  (duration: 97.751063ms)","trace[1563454621] 'compare'  (duration: 15.705373ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:21:54.004318Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5745}
{"level":"info","ts":"2026-03-10T18:21:54.088313Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5745,"took":"81.733283ms","hash":3410429310,"current-db-size-bytes":42909696,"current-db-size":"43 MB","current-db-size-in-use-bytes":23117824,"current-db-size-in-use":"23 MB"}
{"level":"info","ts":"2026-03-10T18:21:54.088382Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3410429310,"revision":5745,"compact-revision":2789}
{"level":"info","ts":"2026-03-10T18:22:22.64177Z","caller":"traceutil/trace.go:171","msg":"trace[750789034] transaction","detail":"{read_only:false; response_revision:7950; number_of_response:1; }","duration":"306.406686ms","start":"2026-03-10T18:22:22.33533Z","end":"2026-03-10T18:22:22.641736Z","steps":["trace[750789034] 'process raft request'  (duration: 306.162981ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:22:22.642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:22:22.335307Z","time spent":"306.57392ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:7854 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:600 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >"}
{"level":"info","ts":"2026-03-10T18:22:22.652665Z","caller":"traceutil/trace.go:171","msg":"trace[1158241688] linearizableReadLoop","detail":"{readStateIndex:8254; appliedIndex:8251; }","duration":"145.685149ms","start":"2026-03-10T18:22:22.506952Z","end":"2026-03-10T18:22:22.652638Z","steps":["trace[1158241688] 'read index received'  (duration: 134.503572ms)","trace[1158241688] 'applied index is now lower than readState.Index'  (duration: 11.180137ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:22:22.652704Z","caller":"traceutil/trace.go:171","msg":"trace[1556143287] transaction","detail":"{read_only:false; response_revision:7951; number_of_response:1; }","duration":"280.278302ms","start":"2026-03-10T18:22:22.372406Z","end":"2026-03-10T18:22:22.652685Z","steps":["trace[1556143287] 'process raft request'  (duration: 280.045647ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:22:22.652861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.657824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-03-10T18:22:22.652898Z","caller":"traceutil/trace.go:171","msg":"trace[1979553911] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:7952; }","duration":"133.751556ms","start":"2026-03-10T18:22:22.519133Z","end":"2026-03-10T18:22:22.652885Z","steps":["trace[1979553911] 'agreement among raft nodes before linearized reading'  (duration: 133.635323ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:22:22.653054Z","caller":"traceutil/trace.go:171","msg":"trace[466201164] transaction","detail":"{read_only:false; response_revision:7952; number_of_response:1; }","duration":"279.298361ms","start":"2026-03-10T18:22:22.373699Z","end":"2026-03-10T18:22:22.652997Z","steps":["trace[466201164] 'process raft request'  (duration: 278.902043ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:22:22.653065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.077966ms","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-10T18:22:22.653174Z","caller":"traceutil/trace.go:171","msg":"trace[527265033] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7952; }","duration":"146.275951ms","start":"2026-03-10T18:22:22.506871Z","end":"2026-03-10T18:22:22.653147Z","steps":["trace[527265033] 'agreement among raft nodes before linearized reading'  (duration: 145.878763ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:22:32.8273Z","caller":"traceutil/trace.go:171","msg":"trace[646661114] linearizableReadLoop","detail":"{readStateIndex:8351; appliedIndex:8349; }","duration":"121.463445ms","start":"2026-03-10T18:22:32.705806Z","end":"2026-03-10T18:22:32.827269Z","steps":["trace[646661114] 'read index received'  (duration: 8.99426ms)","trace[646661114] 'applied index is now lower than readState.Index'  (duration: 112.467274ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:22:32.827547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.132572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-03-10T18:22:32.827592Z","caller":"traceutil/trace.go:171","msg":"trace[842574023] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:8044; }","duration":"108.223424ms","start":"2026-03-10T18:22:32.719357Z","end":"2026-03-10T18:22:32.82758Z","steps":["trace[842574023] 'agreement among raft nodes before linearized reading'  (duration: 108.087431ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:22:32.827625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.805122ms","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-10T18:22:32.827722Z","caller":"traceutil/trace.go:171","msg":"trace[1498684764] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8044; }","duration":"121.947616ms","start":"2026-03-10T18:22:32.705755Z","end":"2026-03-10T18:22:32.827702Z","steps":["trace[1498684764] 'agreement among raft nodes before linearized reading'  (duration: 121.67802ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:22:32.827398Z","caller":"traceutil/trace.go:171","msg":"trace[1089518542] transaction","detail":"{read_only:false; response_revision:8044; number_of_response:1; }","duration":"136.873392ms","start":"2026-03-10T18:22:32.690479Z","end":"2026-03-10T18:22:32.827352Z","steps":["trace[1089518542] 'process raft request'  (duration: 58.256545ms)","trace[1089518542] 'compare'  (duration: 78.378332ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:22:33.868859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.653984ms","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-10T18:22:33.868968Z","caller":"traceutil/trace.go:171","msg":"trace[836888188] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8051; }","duration":"133.824767ms","start":"2026-03-10T18:22:33.735122Z","end":"2026-03-10T18:22:33.868947Z","steps":["trace[836888188] 'range keys from in-memory index tree'  (duration: 133.382738ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:22:33.869162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.765314ms","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-10T18:22:33.869277Z","caller":"traceutil/trace.go:171","msg":"trace[1745474502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8051; }","duration":"132.955719ms","start":"2026-03-10T18:22:33.736304Z","end":"2026-03-10T18:22:33.86926Z","steps":["trace[1745474502] 'range keys from in-memory index tree'  (duration: 132.55404ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:22:33.869202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.75658ms","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-10T18:22:33.869787Z","caller":"traceutil/trace.go:171","msg":"trace[2077567923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8051; }","duration":"212.642369ms","start":"2026-03-10T18:22:33.657095Z","end":"2026-03-10T18:22:33.869738Z","steps":["trace[2077567923] 'range keys from in-memory index tree'  (duration: 211.456293ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:26:13.17701Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1082227b3dfb8db7","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-03-10T18:26:13.183652Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-03-10T18:26:13.183812Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"info","ts":"2026-03-10T18:26:54.011642Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7764}
{"level":"info","ts":"2026-03-10T18:26:54.076609Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7764,"took":"62.257533ms","hash":3845234464,"current-db-size-bytes":42909696,"current-db-size":"43 MB","current-db-size-in-use-bytes":25133056,"current-db-size-in-use":"25 MB"}
{"level":"info","ts":"2026-03-10T18:26:54.076675Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3845234464,"revision":7764,"compact-revision":5745}
{"level":"info","ts":"2026-03-10T18:29:08.206096Z","caller":"traceutil/trace.go:171","msg":"trace[1296231170] transaction","detail":"{read_only:false; response_revision:10918; number_of_response:1; }","duration":"104.015669ms","start":"2026-03-10T18:29:08.102042Z","end":"2026-03-10T18:29:08.206057Z","steps":["trace[1296231170] 'process raft request'  (duration: 103.954438ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:29:08.20618Z","caller":"traceutil/trace.go:171","msg":"trace[412577186] transaction","detail":"{read_only:false; response_revision:10917; number_of_response:1; }","duration":"105.349188ms","start":"2026-03-10T18:29:08.100795Z","end":"2026-03-10T18:29:08.206144Z","steps":["trace[412577186] 'process raft request'  (duration: 105.000351ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:31:54.018158Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9830}
{"level":"info","ts":"2026-03-10T18:31:54.09619Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9830,"took":"75.114178ms","hash":2875674155,"current-db-size-bytes":42909696,"current-db-size":"43 MB","current-db-size-in-use-bytes":22396928,"current-db-size-in-use":"22 MB"}
{"level":"info","ts":"2026-03-10T18:31:54.096263Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2875674155,"revision":9830,"compact-revision":7764}
{"level":"info","ts":"2026-03-10T18:34:59.032517Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000034f6.wal"}
{"level":"info","ts":"2026-03-10T18:35:51.369475Z","caller":"traceutil/trace.go:171","msg":"trace[531396291] transaction","detail":"{read_only:false; response_revision:13393; number_of_response:1; }","duration":"105.45572ms","start":"2026-03-10T18:35:51.263981Z","end":"2026-03-10T18:35:51.369436Z","steps":["trace[531396291] 'process raft request'  (duration: 105.212765ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:35:51.472325Z","caller":"traceutil/trace.go:171","msg":"trace[1548869198] transaction","detail":"{read_only:false; response_revision:13394; number_of_response:1; }","duration":"148.930121ms","start":"2026-03-10T18:35:51.323361Z","end":"2026-03-10T18:35:51.472291Z","steps":["trace[1548869198] 'process raft request'  (duration: 127.671652ms)","trace[1548869198] 'compare'  (duration: 21.009334ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:36:08.178967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.060029ms","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-10T18:36:08.179015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.88497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1857"}
{"level":"info","ts":"2026-03-10T18:36:08.179091Z","caller":"traceutil/trace.go:171","msg":"trace[438128308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13485; }","duration":"154.248153ms","start":"2026-03-10T18:36:08.024819Z","end":"2026-03-10T18:36:08.179067Z","steps":["trace[438128308] 'range keys from in-memory index tree'  (duration: 153.767613ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:36:08.179121Z","caller":"traceutil/trace.go:171","msg":"trace[78922747] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:13485; }","duration":"124.075754ms","start":"2026-03-10T18:36:08.055024Z","end":"2026-03-10T18:36:08.1791Z","steps":["trace[78922747] 'range keys from in-memory index tree'  (duration: 123.659516ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:36:08.191182Z","caller":"traceutil/trace.go:171","msg":"trace[930863561] transaction","detail":"{read_only:false; response_revision:13486; number_of_response:1; }","duration":"100.803753ms","start":"2026-03-10T18:36:08.090356Z","end":"2026-03-10T18:36:08.19116Z","steps":["trace[930863561] 'process raft request'  (duration: 100.559028ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:36:10.316111Z","caller":"traceutil/trace.go:171","msg":"trace[1672153770] transaction","detail":"{read_only:false; response_revision:13503; number_of_response:1; }","duration":"119.392537ms","start":"2026-03-10T18:36:10.196687Z","end":"2026-03-10T18:36:10.31608Z","steps":["trace[1672153770] 'process raft request'  (duration: 22.229151ms)","trace[1672153770] 'compare'  (duration: 96.666015ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:36:10.316226Z","caller":"traceutil/trace.go:171","msg":"trace[1294372] transaction","detail":"{read_only:false; response_revision:13504; number_of_response:1; }","duration":"115.613865ms","start":"2026-03-10T18:36:10.20058Z","end":"2026-03-10T18:36:10.316194Z","steps":["trace[1294372] 'process raft request'  (duration: 115.243397ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:36:31.83504Z","caller":"traceutil/trace.go:171","msg":"trace[1066346177] transaction","detail":"{read_only:false; response_revision:13787; number_of_response:1; }","duration":"213.560568ms","start":"2026-03-10T18:36:31.621437Z","end":"2026-03-10T18:36:31.834997Z","steps":["trace[1066346177] 'process raft request'  (duration: 213.323163ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:36:31.987169Z","caller":"traceutil/trace.go:171","msg":"trace[1324008610] transaction","detail":"{read_only:false; response_revision:13788; number_of_response:1; }","duration":"358.417583ms","start":"2026-03-10T18:36:31.62872Z","end":"2026-03-10T18:36:31.987138Z","steps":["trace[1324008610] 'process raft request'  (duration: 357.909451ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:36:31.987401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:36:31.628691Z","time spent":"358.556075ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:13721 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"info","ts":"2026-03-10T18:36:54.02438Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11936}
{"level":"info","ts":"2026-03-10T18:36:54.107372Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11936,"took":"78.891578ms","hash":1296054930,"current-db-size-bytes":42909696,"current-db-size":"43 MB","current-db-size-in-use-bytes":23859200,"current-db-size-in-use":"24 MB"}
{"level":"info","ts":"2026-03-10T18:36:54.107442Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1296054930,"revision":11936,"compact-revision":9830}
{"level":"info","ts":"2026-03-10T18:37:24.301005Z","caller":"traceutil/trace.go:171","msg":"trace[316932340] transaction","detail":"{read_only:false; response_revision:14395; number_of_response:1; }","duration":"108.630908ms","start":"2026-03-10T18:37:24.192346Z","end":"2026-03-10T18:37:24.300977Z","steps":["trace[316932340] 'process raft request'  (duration: 108.363172ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:37:58.454315Z","caller":"traceutil/trace.go:171","msg":"trace[350970987] linearizableReadLoop","detail":"{readStateIndex:15095; appliedIndex:15094; }","duration":"122.492642ms","start":"2026-03-10T18:37:58.331787Z","end":"2026-03-10T18:37:58.454279Z","steps":["trace[350970987] 'read index received'  (duration: 122.37874ms)","trace[350970987] 'applied index is now lower than readState.Index'  (duration: 112.412µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:37:58.454696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.88549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-48h9p\" ","response":"range_response_count:1 size:22017"}
{"level":"info","ts":"2026-03-10T18:37:58.454804Z","caller":"traceutil/trace.go:171","msg":"trace[474865055] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-48h9p; range_end:; response_count:1; response_revision:14555; }","duration":"123.059943ms","start":"2026-03-10T18:37:58.33172Z","end":"2026-03-10T18:37:58.45478Z","steps":["trace[474865055] 'agreement among raft nodes before linearized reading'  (duration: 122.734687ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:37:58.455445Z","caller":"traceutil/trace.go:171","msg":"trace[615823060] transaction","detail":"{read_only:false; response_revision:14555; number_of_response:1; }","duration":"129.752796ms","start":"2026-03-10T18:37:58.324657Z","end":"2026-03-10T18:37:58.45441Z","steps":["trace[615823060] 'process raft request'  (duration: 129.336637ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:41:54.031034Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13925}
{"level":"info","ts":"2026-03-10T18:41:54.117405Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13925,"took":"82.669214ms","hash":1181978668,"current-db-size-bytes":42909696,"current-db-size":"43 MB","current-db-size-in-use-bytes":30343168,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-03-10T18:41:54.117494Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1181978668,"revision":13925,"compact-revision":11936}
{"level":"info","ts":"2026-03-10T18:44:02.747377Z","caller":"traceutil/trace.go:171","msg":"trace[290822667] transaction","detail":"{read_only:false; response_revision:17043; number_of_response:1; }","duration":"181.416439ms","start":"2026-03-10T18:44:02.56591Z","end":"2026-03-10T18:44:02.747327Z","steps":["trace[290822667] 'process raft request'  (duration: 181.085242ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:44:03.092143Z","caller":"traceutil/trace.go:171","msg":"trace[922753654] linearizableReadLoop","detail":"{readStateIndex:17673; appliedIndex:17671; }","duration":"118.373165ms","start":"2026-03-10T18:44:02.973743Z","end":"2026-03-10T18:44:03.092116Z","steps":["trace[922753654] 'read index received'  (duration: 63.319509ms)","trace[922753654] 'applied index is now lower than readState.Index'  (duration: 55.052195ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:44:03.092261Z","caller":"traceutil/trace.go:171","msg":"trace[62711576] transaction","detail":"{read_only:false; response_revision:17046; number_of_response:1; }","duration":"153.510179ms","start":"2026-03-10T18:44:02.93871Z","end":"2026-03-10T18:44:03.09222Z","steps":["trace[62711576] 'process raft request'  (duration: 153.250843ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:44:03.092353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.575389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-10T18:44:03.092467Z","caller":"traceutil/trace.go:171","msg":"trace[1587033396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17046; }","duration":"118.725542ms","start":"2026-03-10T18:44:02.973712Z","end":"2026-03-10T18:44:03.092438Z","steps":["trace[1587033396] 'agreement among raft nodes before linearized reading'  (duration: 118.511638ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:44:03.5456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.897867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-2slkf\" ","response":"range_response_count:1 size:20774"}
{"level":"info","ts":"2026-03-10T18:44:03.545705Z","caller":"traceutil/trace.go:171","msg":"trace[1005195527] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-2slkf; range_end:; response_count:1; response_revision:17046; }","duration":"404.01211ms","start":"2026-03-10T18:44:03.14167Z","end":"2026-03-10T18:44:03.545682Z","steps":["trace[1005195527] 'range keys from in-memory index tree'  (duration: 403.618161ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:44:03.545757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:44:03.1416Z","time spent":"404.145072ms","remote":"127.0.0.1:58028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20796,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-2slkf\" "}
{"level":"warn","ts":"2026-03-10T18:44:03.546392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.718454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-03-10T18:44:03.546445Z","caller":"traceutil/trace.go:171","msg":"trace[1787281886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17046; }","duration":"266.773635ms","start":"2026-03-10T18:44:03.279659Z","end":"2026-03-10T18:44:03.546432Z","steps":["trace[1787281886] 'range keys from in-memory index tree'  (duration: 266.623312ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:44:03.546711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.141263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"}
{"level":"info","ts":"2026-03-10T18:44:03.54675Z","caller":"traceutil/trace.go:171","msg":"trace[1140591613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17046; }","duration":"267.218085ms","start":"2026-03-10T18:44:03.279523Z","end":"2026-03-10T18:44:03.546741Z","steps":["trace[1140591613] 'range keys from in-memory index tree'  (duration: 266.934799ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:44:03.547471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.82797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-48h9p\" ","response":"range_response_count:1 size:22018"}
{"level":"info","ts":"2026-03-10T18:44:03.547532Z","caller":"traceutil/trace.go:171","msg":"trace[961037407] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-48h9p; range_end:; response_count:1; response_revision:17046; }","duration":"175.929282ms","start":"2026-03-10T18:44:03.37159Z","end":"2026-03-10T18:44:03.547519Z","steps":["trace[961037407] 'range keys from in-memory index tree'  (duration: 175.684047ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:46:54.041155Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16233}
{"level":"info","ts":"2026-03-10T18:46:54.140164Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16233,"took":"95.502191ms","hash":3726053601,"current-db-size-bytes":42909696,"current-db-size":"43 MB","current-db-size-in-use-bytes":30461952,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-03-10T18:46:54.140224Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3726053601,"revision":16233,"compact-revision":13925}
{"level":"info","ts":"2026-03-10T18:48:55.308277Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1082227b3dfb8db7","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-03-10T18:48:55.313733Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002}
{"level":"info","ts":"2026-03-10T18:48:55.313858Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}
{"level":"warn","ts":"2026-03-10T18:49:47.166993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.060786ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10211803135608665898 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:19529 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:18"}
{"level":"info","ts":"2026-03-10T18:49:47.167138Z","caller":"traceutil/trace.go:171","msg":"trace[1052422125] linearizableReadLoop","detail":"{readStateIndex:20254; appliedIndex:20253; }","duration":"198.880419ms","start":"2026-03-10T18:49:46.968235Z","end":"2026-03-10T18:49:47.167116Z","steps":["trace[1052422125] 'read index received'  (duration: 57.460606ms)","trace[1052422125] 'applied index is now lower than readState.Index'  (duration: 141.417763ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:49:47.167362Z","caller":"traceutil/trace.go:171","msg":"trace[1996995437] transaction","detail":"{read_only:false; response_revision:19539; number_of_response:1; }","duration":"309.807576ms","start":"2026-03-10T18:49:46.857457Z","end":"2026-03-10T18:49:47.167265Z","steps":["trace[1996995437] 'process raft request'  (duration: 168.353913ms)","trace[1996995437] 'compare'  (duration: 140.740179ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:49:47.16744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.178175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"}
{"level":"info","ts":"2026-03-10T18:49:47.167539Z","caller":"traceutil/trace.go:171","msg":"trace[88227687] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19539; }","duration":"199.353139ms","start":"2026-03-10T18:49:46.968165Z","end":"2026-03-10T18:49:47.167518Z","steps":["trace[88227687] 'agreement among raft nodes before linearized reading'  (duration: 199.084503ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:49:47.167587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:49:46.857406Z","time spent":"310.073662ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:19529 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-03-10T18:49:47.504283Z","caller":"traceutil/trace.go:171","msg":"trace[1396793828] transaction","detail":"{read_only:false; response_revision:19540; number_of_response:1; }","duration":"332.350903ms","start":"2026-03-10T18:49:47.171891Z","end":"2026-03-10T18:49:47.504242Z","steps":["trace[1396793828] 'process raft request'  (duration: 313.549845ms)","trace[1396793828] 'compare'  (duration: 18.516322ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:49:47.504475Z","caller":"traceutil/trace.go:171","msg":"trace[2013002767] transaction","detail":"{read_only:false; response_revision:19541; number_of_response:1; }","duration":"247.14002ms","start":"2026-03-10T18:49:47.257299Z","end":"2026-03-10T18:49:47.504439Z","steps":["trace[2013002767] 'process raft request'  (duration: 246.875915ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:49:47.504556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:49:47.171865Z","time spent":"332.534727ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:19530 > 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-10T18:49:47.757035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.377842ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10211803135608665905 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/masterleases/199.19.213.40\" mod_revision:19496 > success:<request_put:<key:\"/registry/masterleases/199.19.213.40\" value_size:67 lease:988431098753890093 >> failure:<request_range:<key:\"/registry/masterleases/199.19.213.40\" > >>","response":"size:18"}
{"level":"info","ts":"2026-03-10T18:49:47.757518Z","caller":"traceutil/trace.go:171","msg":"trace[1022370367] linearizableReadLoop","detail":"{readStateIndex:20260; appliedIndex:20257; }","duration":"218.77041ms","start":"2026-03-10T18:49:47.538729Z","end":"2026-03-10T18:49:47.7575Z","steps":["trace[1022370367] 'read index received'  (duration: 84.910277ms)","trace[1022370367] 'applied index is now lower than readState.Index'  (duration: 133.858953ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:49:47.757655Z","caller":"traceutil/trace.go:171","msg":"trace[90183311] transaction","detail":"{read_only:false; response_revision:19542; number_of_response:1; }","duration":"455.698874ms","start":"2026-03-10T18:49:47.301939Z","end":"2026-03-10T18:49:47.757638Z","steps":["trace[90183311] 'process raft request'  (duration: 321.598316ms)","trace[90183311] 'compare'  (duration: 133.044185ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:49:47.757729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:49:47.301907Z","time spent":"455.783435ms","remote":"127.0.0.1:57894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/masterleases/199.19.213.40\" mod_revision:19496 > success:<request_put:<key:\"/registry/masterleases/199.19.213.40\" value_size:67 lease:988431098753890093 >> failure:<request_range:<key:\"/registry/masterleases/199.19.213.40\" > >"}
{"level":"info","ts":"2026-03-10T18:49:47.758648Z","caller":"traceutil/trace.go:171","msg":"trace[2027463764] transaction","detail":"{read_only:false; response_revision:19543; number_of_response:1; }","duration":"249.725615ms","start":"2026-03-10T18:49:47.508906Z","end":"2026-03-10T18:49:47.758632Z","steps":["trace[2027463764] 'process raft request'  (duration: 248.411127ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:49:47.758828Z","caller":"traceutil/trace.go:171","msg":"trace[1122587185] transaction","detail":"{read_only:false; response_revision:19544; number_of_response:1; }","duration":"247.574789ms","start":"2026-03-10T18:49:47.511242Z","end":"2026-03-10T18:49:47.758817Z","steps":["trace[1122587185] 'process raft request'  (duration: 246.214671ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:49:47.759383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.61891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"}
{"level":"info","ts":"2026-03-10T18:49:47.759661Z","caller":"traceutil/trace.go:171","msg":"trace[1204992864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19544; }","duration":"220.954456ms","start":"2026-03-10T18:49:47.538687Z","end":"2026-03-10T18:49:47.759641Z","steps":["trace[1204992864] 'agreement among raft nodes before linearized reading'  (duration: 220.489896ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:49:47.766537Z","caller":"traceutil/trace.go:171","msg":"trace[2130630259] transaction","detail":"{read_only:false; response_revision:19545; number_of_response:1; }","duration":"123.995545ms","start":"2026-03-10T18:49:47.642527Z","end":"2026-03-10T18:49:47.766523Z","steps":["trace[2130630259] 'process raft request'  (duration: 123.916493ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:49:47.766811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.997655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-03-10T18:49:47.7669Z","caller":"traceutil/trace.go:171","msg":"trace[876716781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19545; }","duration":"228.114358ms","start":"2026-03-10T18:49:47.538765Z","end":"2026-03-10T18:49:47.766879Z","steps":["trace[876716781] 'agreement among raft nodes before linearized reading'  (duration: 227.830983ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:49:47.875682Z","caller":"traceutil/trace.go:171","msg":"trace[622326596] linearizableReadLoop","detail":"{readStateIndex:20262; appliedIndex:20261; }","duration":"109.104519ms","start":"2026-03-10T18:49:47.766555Z","end":"2026-03-10T18:49:47.87566Z","steps":["trace[622326596] 'read index received'  (duration: 104.016821ms)","trace[622326596] 'applied index is now lower than readState.Index'  (duration: 5.086378ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:49:47.875814Z","caller":"traceutil/trace.go:171","msg":"trace[1249550260] transaction","detail":"{read_only:false; response_revision:19546; number_of_response:1; }","duration":"110.785265ms","start":"2026-03-10T18:49:47.76498Z","end":"2026-03-10T18:49:47.875766Z","steps":["trace[1249550260] 'process raft request'  (duration: 105.656876ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:49:47.875895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.87489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"}
{"level":"info","ts":"2026-03-10T18:49:47.875948Z","caller":"traceutil/trace.go:171","msg":"trace[1728214571] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:19546; }","duration":"113.978593ms","start":"2026-03-10T18:49:47.761957Z","end":"2026-03-10T18:49:47.875935Z","steps":["trace[1728214571] 'agreement among raft nodes before linearized reading'  (duration: 113.798349ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:49:48.150595Z","caller":"traceutil/trace.go:171","msg":"trace[447508065] linearizableReadLoop","detail":"{readStateIndex:20263; appliedIndex:20262; }","duration":"274.818055ms","start":"2026-03-10T18:49:47.87575Z","end":"2026-03-10T18:49:48.150568Z","steps":["trace[447508065] 'read index received'  (duration: 178.18498ms)","trace[447508065] 'applied index is now lower than readState.Index'  (duration: 96.631545ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:49:48.150695Z","caller":"traceutil/trace.go:171","msg":"trace[633082884] transaction","detail":"{read_only:false; response_revision:19547; number_of_response:1; }","duration":"381.007803ms","start":"2026-03-10T18:49:47.769645Z","end":"2026-03-10T18:49:48.150653Z","steps":["trace[633082884] 'process raft request'  (duration: 284.316826ms)","trace[633082884] 'compare'  (duration: 96.470372ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:49:48.15084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:49:47.769609Z","time spent":"381.154026ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:19534 > 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-10T18:49:48.150977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.975206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:137"}
{"level":"info","ts":"2026-03-10T18:49:48.151053Z","caller":"traceutil/trace.go:171","msg":"trace[1873174496] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:19547; }","duration":"273.095379ms","start":"2026-03-10T18:49:47.877942Z","end":"2026-03-10T18:49:48.151038Z","steps":["trace[1873174496] 'agreement among raft nodes before linearized reading'  (duration: 272.839194ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:49:48.151088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.970109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"}
{"level":"warn","ts":"2026-03-10T18:49:48.151116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.750628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-10T18:49:48.151141Z","caller":"traceutil/trace.go:171","msg":"trace[1690512878] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:19547; }","duration":"128.074981ms","start":"2026-03-10T18:49:48.023054Z","end":"2026-03-10T18:49:48.151129Z","steps":["trace[1690512878] 'agreement among raft nodes before linearized reading'  (duration: 127.927648ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:49:48.151278Z","caller":"traceutil/trace.go:171","msg":"trace[562015544] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:19547; }","duration":"140.983173ms","start":"2026-03-10T18:49:48.010263Z","end":"2026-03-10T18:49:48.151246Z","steps":["trace[562015544] 'agreement among raft nodes before linearized reading'  (duration: 140.669617ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:49:48.151128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.824969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-10T18:49:48.151507Z","caller":"traceutil/trace.go:171","msg":"trace[1093884685] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:19547; }","duration":"200.214457ms","start":"2026-03-10T18:49:47.951194Z","end":"2026-03-10T18:49:48.151409Z","steps":["trace[1093884685] 'agreement among raft nodes before linearized reading'  (duration: 199.799438ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:49:48.151181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.974955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-10T18:49:48.151666Z","caller":"traceutil/trace.go:171","msg":"trace[1927808994] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19547; }","duration":"177.503717ms","start":"2026-03-10T18:49:47.974141Z","end":"2026-03-10T18:49:48.151645Z","steps":["trace[1927808994] 'agreement among raft nodes before linearized reading'  (duration: 176.832393ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:49:48.151715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.747445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"}
{"level":"info","ts":"2026-03-10T18:49:48.151879Z","caller":"traceutil/trace.go:171","msg":"trace[1314818607] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19547; }","duration":"302.001711ms","start":"2026-03-10T18:49:47.849863Z","end":"2026-03-10T18:49:48.151865Z","steps":["trace[1314818607] 'agreement among raft nodes before linearized reading'  (duration: 300.858667ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:49:48.152042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:49:47.849843Z","time spent":"302.158854ms","remote":"127.0.0.1:58028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "}
{"level":"info","ts":"2026-03-10T18:51:54.04919Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18439}
{"level":"info","ts":"2026-03-10T18:51:54.157111Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18439,"took":"103.576273ms","hash":283535897,"current-db-size-bytes":43778048,"current-db-size":"44 MB","current-db-size-in-use-bytes":30695424,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-03-10T18:51:54.157163Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":283535897,"revision":18439,"compact-revision":16233}
{"level":"info","ts":"2026-03-10T18:53:34.607248Z","caller":"traceutil/trace.go:171","msg":"trace[636959155] transaction","detail":"{read_only:false; response_revision:21248; number_of_response:1; }","duration":"113.126096ms","start":"2026-03-10T18:53:34.494091Z","end":"2026-03-10T18:53:34.607217Z","steps":["trace[636959155] 'process raft request'  (duration: 112.925772ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:53:34.608172Z","caller":"traceutil/trace.go:171","msg":"trace[981558774] transaction","detail":"{read_only:false; response_revision:21249; number_of_response:1; }","duration":"110.98004ms","start":"2026-03-10T18:53:34.497168Z","end":"2026-03-10T18:53:34.608148Z","steps":["trace[981558774] 'process raft request'  (duration: 110.838437ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:53:47.290058Z","caller":"traceutil/trace.go:171","msg":"trace[1262374441] transaction","detail":"{read_only:false; number_of_response:1; response_revision:21616; }","duration":"101.709793ms","start":"2026-03-10T18:53:47.188308Z","end":"2026-03-10T18:53:47.290018Z","steps":["trace[1262374441] 'process raft request'  (duration: 100.654851ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:53:47.290844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.926967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1286"}
{"level":"info","ts":"2026-03-10T18:53:47.290943Z","caller":"traceutil/trace.go:171","msg":"trace[1611750586] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:21616; }","duration":"101.09822ms","start":"2026-03-10T18:53:47.189823Z","end":"2026-03-10T18:53:47.290921Z","steps":["trace[1611750586] 'agreement among raft nodes before linearized reading'  (duration: 100.806334ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:53:47.945861Z","caller":"traceutil/trace.go:171","msg":"trace[775578656] transaction","detail":"{read_only:false; response_revision:21646; number_of_response:1; }","duration":"103.934671ms","start":"2026-03-10T18:53:47.841895Z","end":"2026-03-10T18:53:47.94583Z","steps":["trace[775578656] 'process raft request'  (duration: 103.663585ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:53:47.946196Z","caller":"traceutil/trace.go:171","msg":"trace[185057487] transaction","detail":"{read_only:false; response_revision:21647; number_of_response:1; }","duration":"100.670192ms","start":"2026-03-10T18:53:47.845505Z","end":"2026-03-10T18:53:47.946175Z","steps":["trace[185057487] 'process raft request'  (duration: 100.244043ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:53:47.946358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.447421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/capi-kubeadm-control-plane-manager-role\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-03-10T18:53:47.946331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.918403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-10T18:53:47.946445Z","caller":"traceutil/trace.go:171","msg":"trace[1857845997] range","detail":"{range_begin:/registry/clusterroles/capi-kubeadm-control-plane-manager-role; range_end:; response_count:0; response_revision:21647; }","duration":"104.585494ms","start":"2026-03-10T18:53:47.841839Z","end":"2026-03-10T18:53:47.946425Z","steps":["trace[1857845997] 'agreement among raft nodes before linearized reading'  (duration: 104.448451ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:53:47.946488Z","caller":"traceutil/trace.go:171","msg":"trace[1266563482] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:21647; }","duration":"115.138168ms","start":"2026-03-10T18:53:47.831324Z","end":"2026-03-10T18:53:47.946463Z","steps":["trace[1266563482] 'agreement among raft nodes before linearized reading'  (duration: 114.846902ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:54:01.05813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.745752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-10T18:54:01.058232Z","caller":"traceutil/trace.go:171","msg":"trace[1367626120] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:21963; }","duration":"107.911036ms","start":"2026-03-10T18:54:00.950301Z","end":"2026-03-10T18:54:01.058212Z","steps":["trace[1367626120] 'count revisions from in-memory index tree'  (duration: 107.64638ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:54:01.461526Z","caller":"traceutil/trace.go:171","msg":"trace[162381997] transaction","detail":"{read_only:false; response_revision:21964; number_of_response:1; }","duration":"410.265148ms","start":"2026-03-10T18:54:01.051226Z","end":"2026-03-10T18:54:01.461491Z","steps":["trace[162381997] 'process raft request'  (duration: 409.367069ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:54:01.461585Z","caller":"traceutil/trace.go:171","msg":"trace[1903636233] linearizableReadLoop","detail":"{readStateIndex:22760; appliedIndex:22759; }","duration":"402.186767ms","start":"2026-03-10T18:54:01.059341Z","end":"2026-03-10T18:54:01.461527Z","steps":["trace[1903636233] 'read index received'  (duration: 401.046252ms)","trace[1903636233] 'applied index is now lower than readState.Index'  (duration: 1.124244ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:54:01.461747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:54:01.051192Z","time spent":"410.443211ms","remote":"127.0.0.1:43892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418109,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" mod_revision:21049 > success:<request_put:<key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" value_size:418041 >> failure:<request_range:<key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" > >"}
{"level":"info","ts":"2026-03-10T18:54:01.461939Z","caller":"traceutil/trace.go:171","msg":"trace[2068610745] transaction","detail":"{read_only:false; response_revision:21965; number_of_response:1; }","duration":"398.867846ms","start":"2026-03-10T18:54:01.063039Z","end":"2026-03-10T18:54:01.461907Z","steps":["trace[2068610745] 'process raft request'  (duration: 398.385546ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:54:01.46208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:54:01.063017Z","time spent":"398.971217ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:21937 > 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-10T18:54:01.462865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.486414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-10T18:54:01.462976Z","caller":"traceutil/trace.go:171","msg":"trace[1645021851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21965; }","duration":"403.653637ms","start":"2026-03-10T18:54:01.059299Z","end":"2026-03-10T18:54:01.462953Z","steps":["trace[1645021851] 'agreement among raft nodes before linearized reading'  (duration: 402.303499ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:54:01.463028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:54:01.059281Z","time spent":"403.736889ms","remote":"127.0.0.1:57876","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-10T18:54:01.463067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.031485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:560"}
{"level":"info","ts":"2026-03-10T18:54:01.463219Z","caller":"traceutil/trace.go:171","msg":"trace[1809866319] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:21965; }","duration":"199.233618ms","start":"2026-03-10T18:54:01.263948Z","end":"2026-03-10T18:54:01.463181Z","steps":["trace[1809866319] 'agreement among raft nodes before linearized reading'  (duration: 197.920441ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:56:54.061738Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20523}
{"level":"info","ts":"2026-03-10T18:56:54.174472Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20523,"took":"107.683831ms","hash":4168940631,"current-db-size-bytes":46182400,"current-db-size":"46 MB","current-db-size-in-use-bytes":39251968,"current-db-size-in-use":"39 MB"}
{"level":"info","ts":"2026-03-10T18:56:54.174551Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4168940631,"revision":20523,"compact-revision":18439}
{"level":"info","ts":"2026-03-10T18:57:31.14755Z","caller":"traceutil/trace.go:171","msg":"trace[470168104] transaction","detail":"{read_only:false; response_revision:23978; number_of_response:1; }","duration":"135.544089ms","start":"2026-03-10T18:57:31.011939Z","end":"2026-03-10T18:57:31.147483Z","steps":["trace[470168104] 'process raft request'  (duration: 135.213453ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:31.238236Z","caller":"traceutil/trace.go:171","msg":"trace[121579038] transaction","detail":"{read_only:false; response_revision:23979; number_of_response:1; }","duration":"180.347109ms","start":"2026-03-10T18:57:31.057857Z","end":"2026-03-10T18:57:31.238204Z","steps":["trace[121579038] 'process raft request'  (duration: 136.546242ms)","trace[121579038] 'compare'  (duration: 43.595533ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:57:31.238427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.172373ms","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:6"}
{"level":"info","ts":"2026-03-10T18:57:31.238545Z","caller":"traceutil/trace.go:171","msg":"trace[983793525] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:23979; }","duration":"170.365698ms","start":"2026-03-10T18:57:31.068152Z","end":"2026-03-10T18:57:31.238517Z","steps":["trace[983793525] 'agreement among raft nodes before linearized reading'  (duration: 170.154553ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:31.79909Z","caller":"traceutil/trace.go:171","msg":"trace[2069863010] transaction","detail":"{read_only:false; response_revision:23980; number_of_response:1; }","duration":"149.281222ms","start":"2026-03-10T18:57:31.649775Z","end":"2026-03-10T18:57:31.799056Z","steps":["trace[2069863010] 'process raft request'  (duration: 148.963055ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:31.969511Z","caller":"traceutil/trace.go:171","msg":"trace[1332091971] transaction","detail":"{read_only:false; response_revision:23981; number_of_response:1; }","duration":"228.342895ms","start":"2026-03-10T18:57:31.741136Z","end":"2026-03-10T18:57:31.969478Z","steps":["trace[1332091971] 'process raft request'  (duration: 228.073689ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:31.969812Z","caller":"traceutil/trace.go:171","msg":"trace[1331017287] transaction","detail":"{read_only:false; response_revision:23982; number_of_response:1; }","duration":"182.375712ms","start":"2026-03-10T18:57:31.787404Z","end":"2026-03-10T18:57:31.969779Z","steps":["trace[1331017287] 'process raft request'  (duration: 182.021504ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:31.969853Z","caller":"traceutil/trace.go:171","msg":"trace[922967927] transaction","detail":"{read_only:false; response_revision:23984; number_of_response:1; }","duration":"178.873508ms","start":"2026-03-10T18:57:31.790953Z","end":"2026-03-10T18:57:31.969827Z","steps":["trace[922967927] 'process raft request'  (duration: 178.814286ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:31.970029Z","caller":"traceutil/trace.go:171","msg":"trace[86769779] transaction","detail":"{read_only:false; response_revision:23983; number_of_response:1; }","duration":"180.8484ms","start":"2026-03-10T18:57:31.789171Z","end":"2026-03-10T18:57:31.970019Z","steps":["trace[86769779] 'process raft request'  (duration: 180.477022ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:31.973128Z","caller":"traceutil/trace.go:171","msg":"trace[1267425492] transaction","detail":"{read_only:false; response_revision:23985; number_of_response:1; }","duration":"168.955367ms","start":"2026-03-10T18:57:31.804143Z","end":"2026-03-10T18:57:31.973098Z","steps":["trace[1267425492] 'process raft request'  (duration: 168.784953ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:36.575088Z","caller":"traceutil/trace.go:171","msg":"trace[808335557] transaction","detail":"{read_only:false; response_revision:24017; number_of_response:1; }","duration":"140.080817ms","start":"2026-03-10T18:57:36.434972Z","end":"2026-03-10T18:57:36.575052Z","steps":["trace[808335557] 'process raft request'  (duration: 139.880172ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:36.76246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.227394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"}
{"level":"info","ts":"2026-03-10T18:57:36.76256Z","caller":"traceutil/trace.go:171","msg":"trace[1661562506] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24017; }","duration":"111.389918ms","start":"2026-03-10T18:57:36.651147Z","end":"2026-03-10T18:57:36.762537Z","steps":["trace[1661562506] 'range keys from in-memory index tree'  (duration: 111.01788ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:36.947464Z","caller":"traceutil/trace.go:171","msg":"trace[26642122] transaction","detail":"{read_only:false; response_revision:24018; number_of_response:1; }","duration":"179.506911ms","start":"2026-03-10T18:57:36.767915Z","end":"2026-03-10T18:57:36.947422Z","steps":["trace[26642122] 'process raft request'  (duration: 179.262796ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:37.116541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.427606ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10211803135608677940 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:24006 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-03-10T18:57:37.116742Z","caller":"traceutil/trace.go:171","msg":"trace[193847608] transaction","detail":"{read_only:false; response_revision:24019; number_of_response:1; }","duration":"164.593005ms","start":"2026-03-10T18:57:36.952089Z","end":"2026-03-10T18:57:37.116682Z","steps":["trace[193847608] 'process raft request'  (duration: 13.902144ms)","trace[193847608] 'compare'  (duration: 150.304383ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:57:37.116834Z","caller":"traceutil/trace.go:171","msg":"trace[145584035] linearizableReadLoop","detail":"{readStateIndex:24873; appliedIndex:24872; }","duration":"142.768133ms","start":"2026-03-10T18:57:36.974037Z","end":"2026-03-10T18:57:37.116805Z","steps":["trace[145584035] 'read index received'  (duration: 79.182µs)","trace[145584035] 'applied index is now lower than readState.Index'  (duration: 142.685151ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:57:37.117008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.956867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-10T18:57:37.117082Z","caller":"traceutil/trace.go:171","msg":"trace[1273013269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24020; }","duration":"143.084969ms","start":"2026-03-10T18:57:36.973981Z","end":"2026-03-10T18:57:37.117066Z","steps":["trace[1273013269] 'agreement among raft nodes before linearized reading'  (duration: 142.939966ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:37.28036Z","caller":"traceutil/trace.go:171","msg":"trace[1109614113] transaction","detail":"{read_only:false; response_revision:24021; number_of_response:1; }","duration":"106.535336ms","start":"2026-03-10T18:57:37.173792Z","end":"2026-03-10T18:57:37.280327Z","steps":["trace[1109614113] 'process raft request'  (duration: 105.973534ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:38.137552Z","caller":"traceutil/trace.go:171","msg":"trace[2088757015] transaction","detail":"{read_only:false; response_revision:24024; number_of_response:1; }","duration":"149.776881ms","start":"2026-03-10T18:57:37.987743Z","end":"2026-03-10T18:57:38.13752Z","steps":["trace[2088757015] 'process raft request'  (duration: 149.600608ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:38.148235Z","caller":"traceutil/trace.go:171","msg":"trace[1104435490] transaction","detail":"{read_only:false; response_revision:24025; number_of_response:1; }","duration":"150.322173ms","start":"2026-03-10T18:57:37.997899Z","end":"2026-03-10T18:57:38.148221Z","steps":["trace[1104435490] 'process raft request'  (duration: 150.202611ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:38.14838Z","caller":"traceutil/trace.go:171","msg":"trace[868176879] transaction","detail":"{read_only:false; response_revision:24026; number_of_response:1; }","duration":"149.439124ms","start":"2026-03-10T18:57:37.998934Z","end":"2026-03-10T18:57:38.148373Z","steps":["trace[868176879] 'process raft request'  (duration: 149.24619ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:38.148634Z","caller":"traceutil/trace.go:171","msg":"trace[489014958] transaction","detail":"{read_only:false; response_revision:24027; number_of_response:1; }","duration":"149.126117ms","start":"2026-03-10T18:57:37.999489Z","end":"2026-03-10T18:57:38.148615Z","steps":["trace[489014958] 'process raft request'  (duration: 148.856592ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:38.148609Z","caller":"traceutil/trace.go:171","msg":"trace[1335945800] linearizableReadLoop","detail":"{readStateIndex:24882; appliedIndex:24878; }","duration":"140.905594ms","start":"2026-03-10T18:57:38.007669Z","end":"2026-03-10T18:57:38.148575Z","steps":["trace[1335945800] 'read index received'  (duration: 129.791368ms)","trace[1335945800] 'applied index is now lower than readState.Index'  (duration: 11.112496ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:57:38.148865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.172329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"}
{"level":"info","ts":"2026-03-10T18:57:38.148957Z","caller":"traceutil/trace.go:171","msg":"trace[344671193] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24027; }","duration":"141.306332ms","start":"2026-03-10T18:57:38.007626Z","end":"2026-03-10T18:57:38.148933Z","steps":["trace[344671193] 'agreement among raft nodes before linearized reading'  (duration: 141.061107ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:38.322773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.60539ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10211803135608677960 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:24014 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:18"}
{"level":"info","ts":"2026-03-10T18:57:38.32294Z","caller":"traceutil/trace.go:171","msg":"trace[1868480920] transaction","detail":"{read_only:false; response_revision:24029; number_of_response:1; }","duration":"168.794435ms","start":"2026-03-10T18:57:38.154131Z","end":"2026-03-10T18:57:38.322926Z","steps":["trace[1868480920] 'process raft request'  (duration: 168.743783ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:38.322965Z","caller":"traceutil/trace.go:171","msg":"trace[749611960] transaction","detail":"{read_only:false; response_revision:24028; number_of_response:1; }","duration":"168.949638ms","start":"2026-03-10T18:57:38.153985Z","end":"2026-03-10T18:57:38.322935Z","steps":["trace[749611960] 'process raft request'  (duration: 25.108162ms)","trace[749611960] 'compare'  (duration: 143.474488ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:57:39.289636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.074648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"}
{"level":"info","ts":"2026-03-10T18:57:39.289737Z","caller":"traceutil/trace.go:171","msg":"trace[563881547] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24032; }","duration":"158.205091ms","start":"2026-03-10T18:57:39.131509Z","end":"2026-03-10T18:57:39.289714Z","steps":["trace[563881547] 'range keys from in-memory index tree'  (duration: 157.890114ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:39.290047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.223661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"}
{"level":"info","ts":"2026-03-10T18:57:39.290145Z","caller":"traceutil/trace.go:171","msg":"trace[2079563975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24032; }","duration":"167.363644ms","start":"2026-03-10T18:57:39.122761Z","end":"2026-03-10T18:57:39.290124Z","steps":["trace[2079563975] 'range keys from in-memory index tree'  (duration: 167.085188ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:39.401872Z","caller":"traceutil/trace.go:171","msg":"trace[411748893] transaction","detail":"{read_only:false; response_revision:24033; number_of_response:1; }","duration":"114.801791ms","start":"2026-03-10T18:57:39.287033Z","end":"2026-03-10T18:57:39.401835Z","steps":["trace[411748893] 'process raft request'  (duration: 114.587186ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:39.446695Z","caller":"traceutil/trace.go:171","msg":"trace[1146678934] linearizableReadLoop","detail":"{readStateIndex:24889; appliedIndex:24887; }","duration":"113.610936ms","start":"2026-03-10T18:57:39.333056Z","end":"2026-03-10T18:57:39.446667Z","steps":["trace[1146678934] 'read index received'  (duration: 68.533612ms)","trace[1146678934] 'applied index is now lower than readState.Index'  (duration: 45.075804ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:57:39.446902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.83704ms","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-10T18:57:39.446933Z","caller":"traceutil/trace.go:171","msg":"trace[450643532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24034; }","duration":"113.942482ms","start":"2026-03-10T18:57:39.332982Z","end":"2026-03-10T18:57:39.446924Z","steps":["trace[450643532] 'agreement among raft nodes before linearized reading'  (duration: 113.790769ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:39.447065Z","caller":"traceutil/trace.go:171","msg":"trace[1298888076] transaction","detail":"{read_only:false; response_revision:24034; number_of_response:1; }","duration":"151.230703ms","start":"2026-03-10T18:57:39.295796Z","end":"2026-03-10T18:57:39.447027Z","steps":["trace[1298888076] 'process raft request'  (duration: 150.723442ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:39.624666Z","caller":"traceutil/trace.go:171","msg":"trace[2014404459] transaction","detail":"{read_only:false; response_revision:24035; number_of_response:1; }","duration":"169.001998ms","start":"2026-03-10T18:57:39.455628Z","end":"2026-03-10T18:57:39.62463Z","steps":["trace[2014404459] 'process raft request'  (duration: 168.419296ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:39.625015Z","caller":"traceutil/trace.go:171","msg":"trace[341762665] transaction","detail":"{read_only:false; response_revision:24036; number_of_response:1; }","duration":"160.1283ms","start":"2026-03-10T18:57:39.464847Z","end":"2026-03-10T18:57:39.624976Z","steps":["trace[341762665] 'process raft request'  (duration: 159.889875ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:40.611875Z","caller":"traceutil/trace.go:171","msg":"trace[226920905] transaction","detail":"{read_only:false; response_revision:24041; number_of_response:1; }","duration":"259.320082ms","start":"2026-03-10T18:57:40.35251Z","end":"2026-03-10T18:57:40.61183Z","steps":["trace[226920905] 'process raft request'  (duration: 259.146508ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:40.670428Z","caller":"traceutil/trace.go:171","msg":"trace[1519717440] transaction","detail":"{read_only:false; response_revision:24042; number_of_response:1; }","duration":"317.83395ms","start":"2026-03-10T18:57:40.352569Z","end":"2026-03-10T18:57:40.670403Z","steps":["trace[1519717440] 'process raft request'  (duration: 317.634426ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:40.67075Z","caller":"traceutil/trace.go:171","msg":"trace[514505066] transaction","detail":"{read_only:false; response_revision:24044; number_of_response:1; }","duration":"263.9267ms","start":"2026-03-10T18:57:40.406795Z","end":"2026-03-10T18:57:40.670722Z","steps":["trace[514505066] 'process raft request'  (duration: 263.804717ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:40.670851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:57:40.352548Z","time spent":"317.943733ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" mod_revision:24030 > success:<request_put:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" value_size:507 >> failure:<request_range:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" > >"}
{"level":"info","ts":"2026-03-10T18:57:40.671575Z","caller":"traceutil/trace.go:171","msg":"trace[881812962] transaction","detail":"{read_only:false; response_revision:24043; number_of_response:1; }","duration":"266.37602ms","start":"2026-03-10T18:57:40.404408Z","end":"2026-03-10T18:57:40.670784Z","steps":["trace[881812962] 'process raft request'  (duration: 265.92737ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:41.131583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.492085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-10T18:57:41.131684Z","caller":"traceutil/trace.go:171","msg":"trace[1958851412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24045; }","duration":"157.674289ms","start":"2026-03-10T18:57:40.973987Z","end":"2026-03-10T18:57:41.131661Z","steps":["trace[1958851412] 'range keys from in-memory index tree'  (duration: 157.322881ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:41.365593Z","caller":"traceutil/trace.go:171","msg":"trace[734262211] transaction","detail":"{read_only:false; response_revision:24046; number_of_response:1; }","duration":"228.668453ms","start":"2026-03-10T18:57:41.136881Z","end":"2026-03-10T18:57:41.365549Z","steps":["trace[734262211] 'process raft request'  (duration: 228.275724ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:41.515757Z","caller":"traceutil/trace.go:171","msg":"trace[1383958302] transaction","detail":"{read_only:false; response_revision:24047; number_of_response:1; }","duration":"107.326842ms","start":"2026-03-10T18:57:41.408398Z","end":"2026-03-10T18:57:41.515725Z","steps":["trace[1383958302] 'process raft request'  (duration: 104.803118ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:51.525597Z","caller":"traceutil/trace.go:171","msg":"trace[1556173384] transaction","detail":"{read_only:false; response_revision:24115; number_of_response:1; }","duration":"101.762975ms","start":"2026-03-10T18:57:51.423802Z","end":"2026-03-10T18:57:51.525565Z","steps":["trace[1556173384] 'process raft request'  (duration: 101.123561ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:51.829738Z","caller":"traceutil/trace.go:171","msg":"trace[1581584101] linearizableReadLoop","detail":"{readStateIndex:24973; appliedIndex:24972; }","duration":"243.925385ms","start":"2026-03-10T18:57:51.585769Z","end":"2026-03-10T18:57:51.829694Z","steps":["trace[1581584101] 'read index received'  (duration: 243.709251ms)","trace[1581584101] 'applied index is now lower than readState.Index'  (duration: 214.694µs)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:57:51.830261Z","caller":"traceutil/trace.go:171","msg":"trace[485517239] transaction","detail":"{read_only:false; response_revision:24116; number_of_response:1; }","duration":"258.899962ms","start":"2026-03-10T18:57:51.571335Z","end":"2026-03-10T18:57:51.830235Z","steps":["trace[485517239] 'process raft request'  (duration: 258.154967ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:51.83064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.809883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"}
{"level":"warn","ts":"2026-03-10T18:57:51.830711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.963885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-03-10T18:57:51.830825Z","caller":"traceutil/trace.go:171","msg":"trace[1058086714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24116; }","duration":"142.146819ms","start":"2026-03-10T18:57:51.688656Z","end":"2026-03-10T18:57:51.830803Z","steps":["trace[1058086714] 'agreement among raft nodes before linearized reading'  (duration: 141.778672ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:51.830808Z","caller":"traceutil/trace.go:171","msg":"trace[1063782673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24116; }","duration":"245.036798ms","start":"2026-03-10T18:57:51.585746Z","end":"2026-03-10T18:57:51.830783Z","steps":["trace[1063782673] 'agreement among raft nodes before linearized reading'  (duration: 244.506927ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:52.114232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.059544ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10211803135608678177 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:24105 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-03-10T18:57:52.114339Z","caller":"traceutil/trace.go:171","msg":"trace[1006895774] transaction","detail":"{read_only:false; response_revision:24117; number_of_response:1; }","duration":"278.200311ms","start":"2026-03-10T18:57:51.836122Z","end":"2026-03-10T18:57:52.114322Z","steps":["trace[1006895774] 'process raft request'  (duration: 88.963014ms)","trace[1006895774] 'compare'  (duration: 188.971762ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:57:52.119494Z","caller":"traceutil/trace.go:171","msg":"trace[64596811] linearizableReadLoop","detail":"{readStateIndex:24975; appliedIndex:24974; }","duration":"145.507161ms","start":"2026-03-10T18:57:51.973955Z","end":"2026-03-10T18:57:52.119463Z","steps":["trace[64596811] 'read index received'  (duration: 145.367159ms)","trace[64596811] 'applied index is now lower than readState.Index'  (duration: 138.442µs)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:57:52.119597Z","caller":"traceutil/trace.go:171","msg":"trace[1451742259] transaction","detail":"{read_only:false; response_revision:24118; number_of_response:1; }","duration":"282.839569ms","start":"2026-03-10T18:57:51.836719Z","end":"2026-03-10T18:57:52.119559Z","steps":["trace[1451742259] 'process raft request'  (duration: 282.558254ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:52.119698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.727207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-10T18:57:52.119735Z","caller":"traceutil/trace.go:171","msg":"trace[1697145432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24118; }","duration":"145.838319ms","start":"2026-03-10T18:57:51.973886Z","end":"2026-03-10T18:57:52.119724Z","steps":["trace[1697145432] 'agreement among raft nodes before linearized reading'  (duration: 145.743696ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:52.834161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.805598ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10211803135608678184 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:24108 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:18"}
{"level":"info","ts":"2026-03-10T18:57:52.834618Z","caller":"traceutil/trace.go:171","msg":"trace[1463063332] transaction","detail":"{read_only:false; response_revision:24121; number_of_response:1; }","duration":"604.811476ms","start":"2026-03-10T18:57:52.229746Z","end":"2026-03-10T18:57:52.834558Z","steps":["trace[1463063332] 'process raft request'  (duration: 322.507448ms)","trace[1463063332] 'compare'  (duration: 281.491561ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:57:52.834649Z","caller":"traceutil/trace.go:171","msg":"trace[1065565495] transaction","detail":"{read_only:false; response_revision:24123; number_of_response:1; }","duration":"604.790327ms","start":"2026-03-10T18:57:52.229826Z","end":"2026-03-10T18:57:52.834617Z","steps":["trace[1065565495] 'process raft request'  (duration: 604.46767ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:52.834643Z","caller":"traceutil/trace.go:171","msg":"trace[1214015323] transaction","detail":"{read_only:false; response_revision:24122; number_of_response:1; }","duration":"604.832518ms","start":"2026-03-10T18:57:52.229777Z","end":"2026-03-10T18:57:52.834609Z","steps":["trace[1214015323] 'process raft request'  (duration: 604.47378ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:52.834789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:57:52.229815Z","time spent":"604.912179ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:24107 > success:<request_put:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" value_size:453 >> failure:<request_range:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" > >"}
{"level":"warn","ts":"2026-03-10T18:57:52.834861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:57:52.229745Z","time spent":"604.98899ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:24110 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:453 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"warn","ts":"2026-03-10T18:57:52.834881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:57:52.229717Z","time spent":"605.006331ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:24108 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-03-10T18:57:53.167884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.513902ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10211803135608678187 > lease_revoke:<id:0db79cd8ee2ce639>","response":"size:28"}
{"level":"info","ts":"2026-03-10T18:57:53.168155Z","caller":"traceutil/trace.go:171","msg":"trace[688097004] linearizableReadLoop","detail":"{readStateIndex:24981; appliedIndex:24977; }","duration":"841.50902ms","start":"2026-03-10T18:57:52.326624Z","end":"2026-03-10T18:57:53.168133Z","steps":["trace[688097004] 'read index received'  (duration: 225.602278ms)","trace[688097004] 'applied index is now lower than readState.Index'  (duration: 615.905522ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:57:53.168507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.871657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-10T18:57:53.168551Z","caller":"traceutil/trace.go:171","msg":"trace[1717548213] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:24123; }","duration":"841.993699ms","start":"2026-03-10T18:57:52.326543Z","end":"2026-03-10T18:57:53.168537Z","steps":["trace[1717548213] 'agreement among raft nodes before linearized reading'  (duration: 841.902597ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:53.168617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:57:52.326518Z","time spent":"842.085612ms","remote":"127.0.0.1:49246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true "}
{"level":"info","ts":"2026-03-10T18:57:53.168953Z","caller":"traceutil/trace.go:171","msg":"trace[1183582068] transaction","detail":"{read_only:false; response_revision:24124; number_of_response:1; }","duration":"509.477489ms","start":"2026-03-10T18:57:52.659267Z","end":"2026-03-10T18:57:53.168745Z","steps":["trace[1183582068] 'process raft request'  (duration: 508.745043ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:53.169429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:57:52.659237Z","time spent":"509.766594ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/capi-system/controller-leader-election-capi\" mod_revision:24111 > success:<request_put:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" > >"}
{"level":"info","ts":"2026-03-10T18:57:53.384113Z","caller":"traceutil/trace.go:171","msg":"trace[328206950] linearizableReadLoop","detail":"{readStateIndex:24983; appliedIndex:24982; }","duration":"215.882441ms","start":"2026-03-10T18:57:53.168192Z","end":"2026-03-10T18:57:53.384074Z","steps":["trace[328206950] 'read index received'  (duration: 176.6186ms)","trace[328206950] 'applied index is now lower than readState.Index'  (duration: 39.262481ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:57:53.384135Z","caller":"traceutil/trace.go:171","msg":"trace[1866036242] transaction","detail":"{read_only:false; response_revision:24125; number_of_response:1; }","duration":"660.469155ms","start":"2026-03-10T18:57:52.723619Z","end":"2026-03-10T18:57:53.384088Z","steps":["trace[1866036242] 'process raft request'  (duration: 621.236335ms)","trace[1866036242] 'compare'  (duration: 38.74972ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:57:53.384261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.855704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"warn","ts":"2026-03-10T18:57:53.384316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.895344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"}
{"level":"warn","ts":"2026-03-10T18:57:53.384312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:57:52.723602Z","time spent":"660.620198ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" mod_revision:24112 > success:<request_put:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" value_size:507 >> failure:<request_range:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" > >"}
{"level":"info","ts":"2026-03-10T18:57:53.384354Z","caller":"traceutil/trace.go:171","msg":"trace[104401937] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24125; }","duration":"660.975556ms","start":"2026-03-10T18:57:52.723369Z","end":"2026-03-10T18:57:53.384344Z","steps":["trace[104401937] 'agreement among raft nodes before linearized reading'  (duration: 660.825113ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:53.384346Z","caller":"traceutil/trace.go:171","msg":"trace[1035499041] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:24125; }","duration":"576.003347ms","start":"2026-03-10T18:57:52.808319Z","end":"2026-03-10T18:57:53.384322Z","steps":["trace[1035499041] 'agreement among raft nodes before linearized reading'  (duration: 575.842684ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:53.384399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:57:52.723342Z","time spent":"661.048378ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-03-10T18:57:53.384424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:57:52.808295Z","time spent":"576.113759ms","remote":"127.0.0.1:55218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":30,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "}
{"level":"warn","ts":"2026-03-10T18:57:53.384524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.184047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"}
{"level":"info","ts":"2026-03-10T18:57:53.38462Z","caller":"traceutil/trace.go:171","msg":"trace[1026151187] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24125; }","duration":"659.384002ms","start":"2026-03-10T18:57:52.725211Z","end":"2026-03-10T18:57:53.384595Z","steps":["trace[1026151187] 'agreement among raft nodes before linearized reading'  (duration: 659.032035ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:53.384673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:57:52.725185Z","time spent":"659.479753ms","remote":"127.0.0.1:38690","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-10T18:57:53.384881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.619812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:272956"}
{"level":"info","ts":"2026-03-10T18:57:53.384986Z","caller":"traceutil/trace.go:171","msg":"trace[1644597113] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:24125; }","duration":"553.771917ms","start":"2026-03-10T18:57:52.831192Z","end":"2026-03-10T18:57:53.384964Z","steps":["trace[1644597113] 'agreement among raft nodes before linearized reading'  (duration: 553.162844ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:53.385038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:57:52.831168Z","time spent":"553.859338ms","remote":"127.0.0.1:38884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":32,"response size":272978,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "}
{"level":"warn","ts":"2026-03-10T18:57:53.98648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.045898ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10211803135608678192 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:24113 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:18"}
{"level":"info","ts":"2026-03-10T18:57:53.986949Z","caller":"traceutil/trace.go:171","msg":"trace[367058299] linearizableReadLoop","detail":"{readStateIndex:24985; appliedIndex:24983; }","duration":"590.289969ms","start":"2026-03-10T18:57:53.396639Z","end":"2026-03-10T18:57:53.986929Z","steps":["trace[367058299] 'read index received'  (duration: 353.70263ms)","trace[367058299] 'applied index is now lower than readState.Index'  (duration: 236.586129ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:57:53.98708Z","caller":"traceutil/trace.go:171","msg":"trace[1940125029] transaction","detail":"{read_only:false; response_revision:24127; number_of_response:1; }","duration":"597.045062ms","start":"2026-03-10T18:57:53.390024Z","end":"2026-03-10T18:57:53.987069Z","steps":["trace[1940125029] 'process raft request'  (duration: 596.582903ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:53.987152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:57:53.389997Z","time spent":"597.114913ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:24114 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-03-10T18:57:53.987319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.691528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-10T18:57:53.987361Z","caller":"traceutil/trace.go:171","msg":"trace[1529331594] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:24127; }","duration":"590.781ms","start":"2026-03-10T18:57:53.396563Z","end":"2026-03-10T18:57:53.987344Z","steps":["trace[1529331594] 'agreement among raft nodes before linearized reading'  (duration: 590.711339ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:53.987401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:57:53.396536Z","time spent":"590.855131ms","remote":"127.0.0.1:55892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "}
{"level":"info","ts":"2026-03-10T18:57:53.987374Z","caller":"traceutil/trace.go:171","msg":"trace[1550362827] transaction","detail":"{read_only:false; response_revision:24126; number_of_response:1; }","duration":"598.086174ms","start":"2026-03-10T18:57:53.389251Z","end":"2026-03-10T18:57:53.987337Z","steps":["trace[1550362827] 'process raft request'  (duration: 361.073596ms)","trace[1550362827] 'compare'  (duration: 235.67334ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:57:53.987575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:57:53.389211Z","time spent":"598.249578ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:24113 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-03-10T18:57:54.000075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.634537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-10T18:57:54.000153Z","caller":"traceutil/trace.go:171","msg":"trace[1946279978] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:24128; }","duration":"522.754979ms","start":"2026-03-10T18:57:53.477376Z","end":"2026-03-10T18:57:54.000131Z","steps":["trace[1946279978] 'agreement among raft nodes before linearized reading'  (duration: 522.617856ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:54.000175Z","caller":"traceutil/trace.go:171","msg":"trace[1986765841] transaction","detail":"{read_only:false; response_revision:24128; number_of_response:1; }","duration":"163.940262ms","start":"2026-03-10T18:57:53.836209Z","end":"2026-03-10T18:57:54.000149Z","steps":["trace[1986765841] 'process raft request'  (duration: 163.544343ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:54.000061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.866806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-03-10T18:57:54.000217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:57:53.477345Z","time spent":"522.859862ms","remote":"127.0.0.1:39162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true "}
{"level":"info","ts":"2026-03-10T18:57:54.000266Z","caller":"traceutil/trace.go:171","msg":"trace[937604255] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:24128; }","duration":"254.144722ms","start":"2026-03-10T18:57:53.746099Z","end":"2026-03-10T18:57:54.000244Z","steps":["trace[937604255] 'agreement among raft nodes before linearized reading'  (duration: 253.866666ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:54.00069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.055063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"}
{"level":"info","ts":"2026-03-10T18:57:54.000808Z","caller":"traceutil/trace.go:171","msg":"trace[575586215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24128; }","duration":"470.238627ms","start":"2026-03-10T18:57:53.530547Z","end":"2026-03-10T18:57:54.000786Z","steps":["trace[575586215] 'agreement among raft nodes before linearized reading'  (duration: 469.509902ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:54.000864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:57:53.530522Z","time spent":"470.332649ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-03-10T18:57:54.639748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.27328ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10211803135608678203 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" mod_revision:24129 > success:<request_put:<key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" value_size:1873 >> failure:<request_range:<key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" > >>","response":"size:18"}
{"level":"info","ts":"2026-03-10T18:57:54.640017Z","caller":"traceutil/trace.go:171","msg":"trace[540833931] transaction","detail":"{read_only:false; response_revision:24131; number_of_response:1; }","duration":"611.719033ms","start":"2026-03-10T18:57:54.028265Z","end":"2026-03-10T18:57:54.639984Z","steps":["trace[540833931] 'process raft request'  (duration: 301.071735ms)","trace[540833931] 'compare'  (duration: 309.629207ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:57:54.640196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:57:54.028236Z","time spent":"611.852845ms","remote":"127.0.0.1:55892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1946,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" mod_revision:24129 > success:<request_put:<key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" value_size:1873 >> failure:<request_range:<key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" > >"}
{"level":"info","ts":"2026-03-10T18:57:54.640195Z","caller":"traceutil/trace.go:171","msg":"trace[511546434] transaction","detail":"{read_only:false; response_revision:24132; number_of_response:1; }","duration":"374.98347ms","start":"2026-03-10T18:57:54.265165Z","end":"2026-03-10T18:57:54.640148Z","steps":["trace[511546434] 'process raft request'  (duration: 374.856038ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:57:54.640216Z","caller":"traceutil/trace.go:171","msg":"trace[1613482399] linearizableReadLoop","detail":"{readStateIndex:24989; appliedIndex:24988; }","duration":"521.070673ms","start":"2026-03-10T18:57:54.119026Z","end":"2026-03-10T18:57:54.640097Z","steps":["trace[1613482399] 'read index received'  (duration: 210.332263ms)","trace[1613482399] 'applied index is now lower than readState.Index'  (duration: 310.73553ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:57:54.640306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:57:54.265129Z","time spent":"375.136844ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:24120 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:464 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-03-10T18:57:54.640457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.425501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-03-10T18:57:54.641316Z","caller":"traceutil/trace.go:171","msg":"trace[715143265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24132; }","duration":"522.28966ms","start":"2026-03-10T18:57:54.119003Z","end":"2026-03-10T18:57:54.641293Z","steps":["trace[715143265] 'agreement among raft nodes before linearized reading'  (duration: 521.271768ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:54.641363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:57:54.118922Z","time spent":"522.428792ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-03-10T18:57:54.641407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.628979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"}
{"level":"info","ts":"2026-03-10T18:57:54.641469Z","caller":"traceutil/trace.go:171","msg":"trace[193332096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24132; }","duration":"515.74122ms","start":"2026-03-10T18:57:54.125706Z","end":"2026-03-10T18:57:54.641448Z","steps":["trace[193332096] 'agreement among raft nodes before linearized reading'  (duration: 515.465424ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:54.641516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:57:54.1257Z","time spent":"515.807382ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-03-10T18:57:54.64175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.374856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-5f8f8d5594-lgg5c.189b8fdc393ea136\" ","response":"range_response_count:1 size:783"}
{"level":"info","ts":"2026-03-10T18:57:54.641804Z","caller":"traceutil/trace.go:171","msg":"trace[588286609] range","detail":"{range_begin:/registry/events/openstack/magnum-api-5f8f8d5594-lgg5c.189b8fdc393ea136; range_end:; response_count:1; response_revision:24132; }","duration":"322.462058ms","start":"2026-03-10T18:57:54.319331Z","end":"2026-03-10T18:57:54.641793Z","steps":["trace[588286609] 'agreement among raft nodes before linearized reading'  (duration: 322.255434ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:57:54.64183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:57:54.319302Z","time spent":"322.522049ms","remote":"127.0.0.1:57932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":805,"request content":"key:\"/registry/events/openstack/magnum-api-5f8f8d5594-lgg5c.189b8fdc393ea136\" "}
{"level":"info","ts":"2026-03-10T18:57:55.954009Z","caller":"traceutil/trace.go:171","msg":"trace[699115610] transaction","detail":"{read_only:false; response_revision:24196; number_of_response:1; }","duration":"107.014776ms","start":"2026-03-10T18:57:55.846952Z","end":"2026-03-10T18:57:55.953966Z","steps":["trace[699115610] 'process raft request'  (duration: 106.696099ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:03.320508Z","caller":"traceutil/trace.go:171","msg":"trace[294649174] transaction","detail":"{read_only:false; response_revision:24272; number_of_response:1; }","duration":"107.539968ms","start":"2026-03-10T18:58:03.212936Z","end":"2026-03-10T18:58:03.320476Z","steps":["trace[294649174] 'process raft request'  (duration: 107.336844ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:03.620459Z","caller":"traceutil/trace.go:171","msg":"trace[1692283653] linearizableReadLoop","detail":"{readStateIndex:25134; appliedIndex:25133; }","duration":"278.59291ms","start":"2026-03-10T18:58:03.341842Z","end":"2026-03-10T18:58:03.620435Z","steps":["trace[1692283653] 'read index received'  (duration: 278.473637ms)","trace[1692283653] 'applied index is now lower than readState.Index'  (duration: 117.403µs)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:58:03.620772Z","caller":"traceutil/trace.go:171","msg":"trace[151418210] transaction","detail":"{read_only:false; response_revision:24273; number_of_response:1; }","duration":"280.655763ms","start":"2026-03-10T18:58:03.340107Z","end":"2026-03-10T18:58:03.620763Z","steps":["trace[151418210] 'process raft request'  (duration: 280.131412ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:03.620971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.114881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-10T18:58:03.620999Z","caller":"traceutil/trace.go:171","msg":"trace[1236060578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24273; }","duration":"279.185692ms","start":"2026-03-10T18:58:03.341807Z","end":"2026-03-10T18:58:03.620993Z","steps":["trace[1236060578] 'agreement among raft nodes before linearized reading'  (duration: 279.118891ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:03.627631Z","caller":"traceutil/trace.go:171","msg":"trace[1805264489] transaction","detail":"{read_only:false; response_revision:24274; number_of_response:1; }","duration":"201.354143ms","start":"2026-03-10T18:58:03.426245Z","end":"2026-03-10T18:58:03.6276Z","steps":["trace[1805264489] 'process raft request'  (duration: 200.829342ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:03.627916Z","caller":"traceutil/trace.go:171","msg":"trace[483595998] transaction","detail":"{read_only:false; response_revision:24275; number_of_response:1; }","duration":"165.27507ms","start":"2026-03-10T18:58:03.462628Z","end":"2026-03-10T18:58:03.627903Z","steps":["trace[483595998] 'process raft request'  (duration: 164.621906ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:03.628306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.183581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-10T18:58:03.628347Z","caller":"traceutil/trace.go:171","msg":"trace[868605337] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:24275; }","duration":"244.314113ms","start":"2026-03-10T18:58:03.384018Z","end":"2026-03-10T18:58:03.628332Z","steps":["trace[868605337] 'agreement among raft nodes before linearized reading'  (duration: 244.215261ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:03.797626Z","caller":"traceutil/trace.go:171","msg":"trace[320055005] transaction","detail":"{read_only:false; response_revision:24276; number_of_response:1; }","duration":"168.008528ms","start":"2026-03-10T18:58:03.629591Z","end":"2026-03-10T18:58:03.797599Z","steps":["trace[320055005] 'process raft request'  (duration: 159.017238ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:04.102563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.833775ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10211803135608678582 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/endpointslices/openstack/rabbitmq-manila-snqgl\" mod_revision:24146 > success:<request_put:<key:\"/registry/endpointslices/openstack/rabbitmq-manila-snqgl\" value_size:1350 >> failure:<request_range:<key:\"/registry/endpointslices/openstack/rabbitmq-manila-snqgl\" > >>","response":"size:18"}
{"level":"info","ts":"2026-03-10T18:58:04.102965Z","caller":"traceutil/trace.go:171","msg":"trace[1656665171] transaction","detail":"{read_only:false; response_revision:24277; number_of_response:1; }","duration":"469.8702ms","start":"2026-03-10T18:58:03.633074Z","end":"2026-03-10T18:58:04.102944Z","steps":["trace[1656665171] 'process raft request'  (duration: 343.545605ms)","trace[1656665171] 'compare'  (duration: 125.64809ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:58:04.103026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:58:03.63306Z","time spent":"469.940122ms","remote":"127.0.0.1:38698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1414,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/endpointslices/openstack/rabbitmq-manila-snqgl\" mod_revision:24146 > success:<request_put:<key:\"/registry/endpointslices/openstack/rabbitmq-manila-snqgl\" value_size:1350 >> failure:<request_range:<key:\"/registry/endpointslices/openstack/rabbitmq-manila-snqgl\" > >"}
{"level":"info","ts":"2026-03-10T18:58:04.103393Z","caller":"traceutil/trace.go:171","msg":"trace[584385414] transaction","detail":"{read_only:false; response_revision:24279; number_of_response:1; }","duration":"469.536292ms","start":"2026-03-10T18:58:03.633823Z","end":"2026-03-10T18:58:04.103359Z","steps":["trace[584385414] 'process raft request'  (duration: 469.338278ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:04.103511Z","caller":"traceutil/trace.go:171","msg":"trace[1960460810] transaction","detail":"{read_only:false; response_revision:24280; number_of_response:1; }","duration":"469.358368ms","start":"2026-03-10T18:58:03.634138Z","end":"2026-03-10T18:58:04.103497Z","steps":["trace[1960460810] 'process raft request'  (duration: 469.171734ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:04.103537Z","caller":"traceutil/trace.go:171","msg":"trace[1806009871] linearizableReadLoop","detail":"{readStateIndex:25141; appliedIndex:25136; }","duration":"445.045705ms","start":"2026-03-10T18:58:03.658474Z","end":"2026-03-10T18:58:04.10352Z","steps":["trace[1806009871] 'read index received'  (duration: 130.153437ms)","trace[1806009871] 'applied index is now lower than readState.Index'  (duration: 314.891258ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:58:04.103561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:58:03.633817Z","time spent":"469.664525ms","remote":"127.0.0.1:58020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":884,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/services/endpoints/openstack/rabbitmq-manila\" mod_revision:24145 > success:<request_put:<key:\"/registry/services/endpoints/openstack/rabbitmq-manila\" value_size:822 >> failure:<request_range:<key:\"/registry/services/endpoints/openstack/rabbitmq-manila\" > >"}
{"level":"warn","ts":"2026-03-10T18:58:04.103598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:58:03.634132Z","time spent":"469.409489ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/cert-manager/cert-manager-controller\" mod_revision:24097 > 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-10T18:58:04.103762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.846421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-03-10T18:58:04.103756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.269109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-10T18:58:04.103802Z","caller":"traceutil/trace.go:171","msg":"trace[280618965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24281; }","duration":"131.916463ms","start":"2026-03-10T18:58:03.971874Z","end":"2026-03-10T18:58:04.10379Z","steps":["trace[280618965] 'agreement among raft nodes before linearized reading'  (duration: 131.849062ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:04.103811Z","caller":"traceutil/trace.go:171","msg":"trace[1372717621] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:24281; }","duration":"445.36953ms","start":"2026-03-10T18:58:03.658429Z","end":"2026-03-10T18:58:04.103799Z","steps":["trace[1372717621] 'agreement among raft nodes before linearized reading'  (duration: 445.175636ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:04.103875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:58:03.658414Z","time spent":"445.425542ms","remote":"127.0.0.1:58044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "}
{"level":"info","ts":"2026-03-10T18:58:04.104238Z","caller":"traceutil/trace.go:171","msg":"trace[1430889322] transaction","detail":"{read_only:false; response_revision:24278; number_of_response:1; }","duration":"469.602613ms","start":"2026-03-10T18:58:03.633664Z","end":"2026-03-10T18:58:04.103267Z","steps":["trace[1430889322] 'process raft request'  (duration: 468.990641ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:04.104335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:58:03.63364Z","time spent":"470.634436ms","remote":"127.0.0.1:38698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1489,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/endpointslices/openstack/rabbitmq-manila-nodes-56khn\" mod_revision:24138 > success:<request_put:<key:\"/registry/endpointslices/openstack/rabbitmq-manila-nodes-56khn\" value_size:1419 >> failure:<request_range:<key:\"/registry/endpointslices/openstack/rabbitmq-manila-nodes-56khn\" > >"}
{"level":"info","ts":"2026-03-10T18:58:26.39199Z","caller":"traceutil/trace.go:171","msg":"trace[261783960] transaction","detail":"{read_only:false; response_revision:24453; number_of_response:1; }","duration":"210.438186ms","start":"2026-03-10T18:58:26.1815Z","end":"2026-03-10T18:58:26.391938Z","steps":["trace[261783960] 'process raft request'  (duration: 199.147407ms)","trace[261783960] 'compare'  (duration: 10.919741ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:58:26.39196Z","caller":"traceutil/trace.go:171","msg":"trace[518124649] linearizableReadLoop","detail":"{readStateIndex:25319; appliedIndex:25317; }","duration":"157.481916ms","start":"2026-03-10T18:58:26.234429Z","end":"2026-03-10T18:58:26.39191Z","steps":["trace[518124649] 'read index received'  (duration: 10.471752ms)","trace[518124649] 'applied index is now lower than readState.Index'  (duration: 147.007164ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:58:26.392234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.777502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"}
{"level":"info","ts":"2026-03-10T18:58:26.392348Z","caller":"traceutil/trace.go:171","msg":"trace[1862446408] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24453; }","duration":"157.941575ms","start":"2026-03-10T18:58:26.234384Z","end":"2026-03-10T18:58:26.392326Z","steps":["trace[1862446408] 'agreement among raft nodes before linearized reading'  (duration: 157.636049ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:26.392399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.722946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"}
{"level":"info","ts":"2026-03-10T18:58:26.392471Z","caller":"traceutil/trace.go:171","msg":"trace[1676934172] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24453; }","duration":"154.821948ms","start":"2026-03-10T18:58:26.237626Z","end":"2026-03-10T18:58:26.392448Z","steps":["trace[1676934172] 'agreement among raft nodes before linearized reading'  (duration: 154.599233ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:26.392652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.409404ms","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-10T18:58:26.392763Z","caller":"traceutil/trace.go:171","msg":"trace[1139010306] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24453; }","duration":"149.580277ms","start":"2026-03-10T18:58:26.243164Z","end":"2026-03-10T18:58:26.392744Z","steps":["trace[1139010306] 'agreement among raft nodes before linearized reading'  (duration: 149.315932ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:27.266098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.490935ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10211803135608679047 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:24449 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:18"}
{"level":"info","ts":"2026-03-10T18:58:27.266602Z","caller":"traceutil/trace.go:171","msg":"trace[860689105] transaction","detail":"{read_only:false; response_revision:24461; number_of_response:1; }","duration":"287.692543ms","start":"2026-03-10T18:58:26.978888Z","end":"2026-03-10T18:58:27.26658Z","steps":["trace[860689105] 'process raft request'  (duration: 95.606535ms)","trace[860689105] 'compare'  (duration: 191.23738ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:58:27.267142Z","caller":"traceutil/trace.go:171","msg":"trace[1316885742] transaction","detail":"{read_only:false; response_revision:24462; number_of_response:1; }","duration":"286.448655ms","start":"2026-03-10T18:58:26.980684Z","end":"2026-03-10T18:58:27.267133Z","steps":["trace[1316885742] 'process raft request'  (duration: 285.507196ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:27.267552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.636429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-10T18:58:27.267872Z","caller":"traceutil/trace.go:171","msg":"trace[1400974713] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:24463; }","duration":"218.007146ms","start":"2026-03-10T18:58:27.04984Z","end":"2026-03-10T18:58:27.267847Z","steps":["trace[1400974713] 'agreement among raft nodes before linearized reading'  (duration: 217.569317ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:27.267938Z","caller":"traceutil/trace.go:171","msg":"trace[796236927] linearizableReadLoop","detail":"{readStateIndex:25328; appliedIndex:25326; }","duration":"217.469906ms","start":"2026-03-10T18:58:27.049879Z","end":"2026-03-10T18:58:27.267349Z","steps":["trace[796236927] 'read index received'  (duration: 24.627622ms)","trace[796236927] 'applied index is now lower than readState.Index'  (duration: 192.837534ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:58:27.290216Z","caller":"traceutil/trace.go:171","msg":"trace[578775574] transaction","detail":"{read_only:false; response_revision:24464; number_of_response:1; }","duration":"100.530959ms","start":"2026-03-10T18:58:27.189638Z","end":"2026-03-10T18:58:27.290169Z","steps":["trace[578775574] 'process raft request'  (duration: 100.243553ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:27.290352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.052533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.40\" ","response":"range_response_count:1 size:137"}
{"level":"info","ts":"2026-03-10T18:58:27.290479Z","caller":"traceutil/trace.go:171","msg":"trace[888400060] range","detail":"{range_begin:/registry/masterleases/199.19.213.40; range_end:; response_count:1; response_revision:24464; }","duration":"180.238637ms","start":"2026-03-10T18:58:27.11021Z","end":"2026-03-10T18:58:27.290449Z","steps":["trace[888400060] 'agreement among raft nodes before linearized reading'  (duration: 179.848899ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:27.523005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.064911ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10211803135608679058 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/masterleases/199.19.213.40\" mod_revision:24382 > success:<request_put:<key:\"/registry/masterleases/199.19.213.40\" value_size:67 lease:988431098753903247 >> failure:<request_range:<key:\"/registry/masterleases/199.19.213.40\" > >>","response":"size:18"}
{"level":"info","ts":"2026-03-10T18:58:27.523374Z","caller":"traceutil/trace.go:171","msg":"trace[1201580954] linearizableReadLoop","detail":"{readStateIndex:25332; appliedIndex:25331; }","duration":"155.636685ms","start":"2026-03-10T18:58:27.367686Z","end":"2026-03-10T18:58:27.523323Z","steps":["trace[1201580954] 'read index received'  (duration: 27.158365ms)","trace[1201580954] 'applied index is now lower than readState.Index'  (duration: 128.47485ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:58:27.523625Z","caller":"traceutil/trace.go:171","msg":"trace[51596007] transaction","detail":"{read_only:false; response_revision:24465; number_of_response:1; }","duration":"160.928759ms","start":"2026-03-10T18:58:27.362676Z","end":"2026-03-10T18:58:27.523605Z","steps":["trace[51596007] 'process raft request'  (duration: 32.165632ms)","trace[51596007] 'compare'  (duration: 127.903948ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:58:27.523692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.986504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9547"}
{"level":"info","ts":"2026-03-10T18:58:27.523781Z","caller":"traceutil/trace.go:171","msg":"trace[2112244493] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24466; }","duration":"156.093246ms","start":"2026-03-10T18:58:27.367629Z","end":"2026-03-10T18:58:27.523722Z","steps":["trace[2112244493] 'agreement among raft nodes before linearized reading'  (duration: 155.878762ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:27.770382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.698705ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-10T18:58:27.770558Z","caller":"traceutil/trace.go:171","msg":"trace[146761161] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24466; }","duration":"110.879779ms","start":"2026-03-10T18:58:27.659644Z","end":"2026-03-10T18:58:27.770524Z","steps":["trace[146761161] 'range keys from in-memory index tree'  (duration: 110.668234ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:28.756682Z","caller":"traceutil/trace.go:171","msg":"trace[161452918] transaction","detail":"{read_only:false; response_revision:24469; number_of_response:1; }","duration":"278.242292ms","start":"2026-03-10T18:58:28.478404Z","end":"2026-03-10T18:58:28.756647Z","steps":["trace[161452918] 'process raft request'  (duration: 278.009707ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:28.793839Z","caller":"traceutil/trace.go:171","msg":"trace[1857793647] transaction","detail":"{read_only:false; response_revision:24470; number_of_response:1; }","duration":"312.729253ms","start":"2026-03-10T18:58:28.481091Z","end":"2026-03-10T18:58:28.793821Z","steps":["trace[1857793647] 'process raft request'  (duration: 312.306674ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:28.793975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:58:28.481075Z","time spent":"312.815814ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:24456 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-03-10T18:58:28.794144Z","caller":"traceutil/trace.go:171","msg":"trace[1148412180] transaction","detail":"{read_only:false; response_revision:24471; number_of_response:1; }","duration":"313.031068ms","start":"2026-03-10T18:58:28.481104Z","end":"2026-03-10T18:58:28.794135Z","steps":["trace[1148412180] 'process raft request'  (duration: 312.389785ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:28.794196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:58:28.481075Z","time spent":"313.09312ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:24455 > 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-10T18:58:28.987124Z","caller":"traceutil/trace.go:171","msg":"trace[1936828501] transaction","detail":"{read_only:false; response_revision:24472; number_of_response:1; }","duration":"103.533452ms","start":"2026-03-10T18:58:28.88356Z","end":"2026-03-10T18:58:28.987093Z","steps":["trace[1936828501] 'process raft request'  (duration: 97.568586ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:28.988737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.159117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-03-10T18:58:28.988839Z","caller":"traceutil/trace.go:171","msg":"trace[905120418] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:24471; }","duration":"141.951726ms","start":"2026-03-10T18:58:28.846863Z","end":"2026-03-10T18:58:28.988815Z","steps":["trace[905120418] 'count revisions from in-memory index tree'  (duration: 138.937213ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:29.219855Z","caller":"traceutil/trace.go:171","msg":"trace[1709307063] transaction","detail":"{read_only:false; response_revision:24474; number_of_response:1; }","duration":"227.036007ms","start":"2026-03-10T18:58:28.992785Z","end":"2026-03-10T18:58:29.219821Z","steps":["trace[1709307063] 'process raft request'  (duration: 215.597535ms)","trace[1709307063] 'compare'  (duration: 10.674336ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:58:29.219927Z","caller":"traceutil/trace.go:171","msg":"trace[580499044] transaction","detail":"{read_only:false; response_revision:24475; number_of_response:1; }","duration":"172.507393ms","start":"2026-03-10T18:58:29.047382Z","end":"2026-03-10T18:58:29.219889Z","steps":["trace[580499044] 'process raft request'  (duration: 172.032893ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:29.432789Z","caller":"traceutil/trace.go:171","msg":"trace[650481066] transaction","detail":"{read_only:false; response_revision:24476; number_of_response:1; }","duration":"159.63441ms","start":"2026-03-10T18:58:29.273121Z","end":"2026-03-10T18:58:29.432756Z","steps":["trace[650481066] 'process raft request'  (duration: 71.186338ms)","trace[650481066] 'compare'  (duration: 87.915691ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:58:29.43307Z","caller":"traceutil/trace.go:171","msg":"trace[2056697679] transaction","detail":"{read_only:false; response_revision:24477; number_of_response:1; }","duration":"158.582437ms","start":"2026-03-10T18:58:29.274445Z","end":"2026-03-10T18:58:29.433027Z","steps":["trace[2056697679] 'process raft request'  (duration: 158.206719ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:29.687381Z","caller":"traceutil/trace.go:171","msg":"trace[1245105851] transaction","detail":"{read_only:false; response_revision:24478; number_of_response:1; }","duration":"156.350921ms","start":"2026-03-10T18:58:29.530967Z","end":"2026-03-10T18:58:29.687318Z","steps":["trace[1245105851] 'process raft request'  (duration: 83.291264ms)","trace[1245105851] 'compare'  (duration: 72.556996ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:58:30.035296Z","caller":"traceutil/trace.go:171","msg":"trace[422292346] linearizableReadLoop","detail":"{readStateIndex:25346; appliedIndex:25345; }","duration":"251.515366ms","start":"2026-03-10T18:58:29.783747Z","end":"2026-03-10T18:58:30.035263Z","steps":["trace[422292346] 'read index received'  (duration: 173.986484ms)","trace[422292346] 'applied index is now lower than readState.Index'  (duration: 77.527432ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:58:30.035424Z","caller":"traceutil/trace.go:171","msg":"trace[1138901894] transaction","detail":"{read_only:false; response_revision:24479; number_of_response:1; }","duration":"258.691577ms","start":"2026-03-10T18:58:29.776704Z","end":"2026-03-10T18:58:30.035396Z","steps":["trace[1138901894] 'process raft request'  (duration: 181.127485ms)","trace[1138901894] 'compare'  (duration: 77.148413ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:58:30.035506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.73373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-10T18:58:30.03556Z","caller":"traceutil/trace.go:171","msg":"trace[673064788] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24479; }","duration":"251.824402ms","start":"2026-03-10T18:58:29.78372Z","end":"2026-03-10T18:58:30.035544Z","steps":["trace[673064788] 'agreement among raft nodes before linearized reading'  (duration: 251.692039ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:31.07531Z","caller":"traceutil/trace.go:171","msg":"trace[1958496137] transaction","detail":"{read_only:false; response_revision:24484; number_of_response:1; }","duration":"249.188717ms","start":"2026-03-10T18:58:30.826079Z","end":"2026-03-10T18:58:31.075267Z","steps":["trace[1958496137] 'process raft request'  (duration: 157.597628ms)","trace[1958496137] 'compare'  (duration: 91.354054ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:58:31.075365Z","caller":"traceutil/trace.go:171","msg":"trace[237425801] linearizableReadLoop","detail":"{readStateIndex:25351; appliedIndex:25350; }","duration":"100.793604ms","start":"2026-03-10T18:58:30.974509Z","end":"2026-03-10T18:58:31.075303Z","steps":["trace[237425801] 'read index received'  (duration: 9.176965ms)","trace[237425801] 'applied index is now lower than readState.Index'  (duration: 91.615429ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:58:31.075819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.307626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-10T18:58:31.075861Z","caller":"traceutil/trace.go:171","msg":"trace[2040897768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24485; }","duration":"101.419868ms","start":"2026-03-10T18:58:30.974431Z","end":"2026-03-10T18:58:31.075851Z","steps":["trace[2040897768] 'agreement among raft nodes before linearized reading'  (duration: 101.047169ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:31.396922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.59371ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10211803135608679118 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:24473 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-03-10T18:58:31.397202Z","caller":"traceutil/trace.go:171","msg":"trace[1344425217] linearizableReadLoop","detail":"{readStateIndex:25353; appliedIndex:25352; }","duration":"250.586546ms","start":"2026-03-10T18:58:31.146597Z","end":"2026-03-10T18:58:31.397184Z","steps":["trace[1344425217] 'read index received'  (duration: 106.676779ms)","trace[1344425217] 'applied index is now lower than readState.Index'  (duration: 143.908157ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:58:31.397356Z","caller":"traceutil/trace.go:171","msg":"trace[1433326857] transaction","detail":"{read_only:false; response_revision:24486; number_of_response:1; }","duration":"316.452482ms","start":"2026-03-10T18:58:31.080855Z","end":"2026-03-10T18:58:31.397308Z","steps":["trace[1433326857] 'process raft request'  (duration: 172.394662ms)","trace[1433326857] 'compare'  (duration: 143.247163ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:58:31.397537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:58:31.08083Z","time spent":"316.608314ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:24473 > 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-10T18:58:31.397551Z","caller":"traceutil/trace.go:171","msg":"trace[655170345] transaction","detail":"{read_only:false; response_revision:24487; number_of_response:1; }","duration":"170.720214ms","start":"2026-03-10T18:58:31.226795Z","end":"2026-03-10T18:58:31.397515Z","steps":["trace[655170345] 'process raft request'  (duration: 170.278935ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:31.397657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.027495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/auth-system/keycloak\" ","response":"range_response_count:1 size:906"}
{"level":"info","ts":"2026-03-10T18:58:31.39776Z","caller":"traceutil/trace.go:171","msg":"trace[183448764] range","detail":"{range_begin:/registry/serviceaccounts/auth-system/keycloak; range_end:; response_count:1; response_revision:24487; }","duration":"251.20846ms","start":"2026-03-10T18:58:31.146532Z","end":"2026-03-10T18:58:31.397741Z","steps":["trace[183448764] 'agreement among raft nodes before linearized reading'  (duration: 250.77685ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:31.397665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.575268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-10T18:58:31.397886Z","caller":"traceutil/trace.go:171","msg":"trace[599839690] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:24487; }","duration":"116.817954ms","start":"2026-03-10T18:58:31.28105Z","end":"2026-03-10T18:58:31.397868Z","steps":["trace[599839690] 'agreement among raft nodes before linearized reading'  (duration: 116.527797ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:31.397967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.014023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-03-10T18:58:31.398179Z","caller":"traceutil/trace.go:171","msg":"trace[1197667306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24487; }","duration":"172.237248ms","start":"2026-03-10T18:58:31.225871Z","end":"2026-03-10T18:58:31.398108Z","steps":["trace[1197667306] 'agreement among raft nodes before linearized reading'  (duration: 171.641885ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:31.624791Z","caller":"traceutil/trace.go:171","msg":"trace[2082840517] transaction","detail":"{read_only:false; response_revision:24489; number_of_response:1; }","duration":"185.10266ms","start":"2026-03-10T18:58:31.439651Z","end":"2026-03-10T18:58:31.624754Z","steps":["trace[2082840517] 'process raft request'  (duration: 104.83414ms)","trace[2082840517] 'compare'  (duration: 79.947273ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:58:31.624922Z","caller":"traceutil/trace.go:171","msg":"trace[6321841] transaction","detail":"{read_only:false; response_revision:24490; number_of_response:1; }","duration":"185.093759ms","start":"2026-03-10T18:58:31.439805Z","end":"2026-03-10T18:58:31.624899Z","steps":["trace[6321841] 'process raft request'  (duration: 184.894465ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:31.939189Z","caller":"traceutil/trace.go:171","msg":"trace[1646795817] linearizableReadLoop","detail":"{readStateIndex:25358; appliedIndex:25357; }","duration":"102.885869ms","start":"2026-03-10T18:58:31.836276Z","end":"2026-03-10T18:58:31.939161Z","steps":["trace[1646795817] 'read index received'  (duration: 102.594843ms)","trace[1646795817] 'applied index is now lower than readState.Index'  (duration: 289.706µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:58:31.939441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.111973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"}
{"level":"info","ts":"2026-03-10T18:58:31.939392Z","caller":"traceutil/trace.go:171","msg":"trace[2064340219] transaction","detail":"{read_only:false; response_revision:24491; number_of_response:1; }","duration":"244.274583ms","start":"2026-03-10T18:58:31.695073Z","end":"2026-03-10T18:58:31.939348Z","steps":["trace[2064340219] 'process raft request'  (duration: 243.857524ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:31.93948Z","caller":"traceutil/trace.go:171","msg":"trace[1367531483] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24491; }","duration":"103.198085ms","start":"2026-03-10T18:58:31.836268Z","end":"2026-03-10T18:58:31.939467Z","steps":["trace[1367531483] 'agreement among raft nodes before linearized reading'  (duration: 102.987971ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:32.42558Z","caller":"traceutil/trace.go:171","msg":"trace[337855880] transaction","detail":"{read_only:false; response_revision:24494; number_of_response:1; }","duration":"128.49149ms","start":"2026-03-10T18:58:32.297062Z","end":"2026-03-10T18:58:32.425554Z","steps":["trace[337855880] 'process raft request'  (duration: 128.276165ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:32.459304Z","caller":"traceutil/trace.go:171","msg":"trace[672834743] linearizableReadLoop","detail":"{readStateIndex:25362; appliedIndex:25360; }","duration":"116.012297ms","start":"2026-03-10T18:58:32.343261Z","end":"2026-03-10T18:58:32.459273Z","steps":["trace[672834743] 'read index received'  (duration: 82.247842ms)","trace[672834743] 'applied index is now lower than readState.Index'  (duration: 33.762474ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:58:32.459488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.214641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-03-10T18:58:32.459574Z","caller":"traceutil/trace.go:171","msg":"trace[994030014] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:24494; }","duration":"116.370464ms","start":"2026-03-10T18:58:32.343184Z","end":"2026-03-10T18:58:32.459555Z","steps":["trace[994030014] 'agreement among raft nodes before linearized reading'  (duration: 116.211811ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:34.119984Z","caller":"traceutil/trace.go:171","msg":"trace[2040703591] linearizableReadLoop","detail":"{readStateIndex:25375; appliedIndex:25374; }","duration":"136.714605ms","start":"2026-03-10T18:58:33.983237Z","end":"2026-03-10T18:58:34.119951Z","steps":["trace[2040703591] 'read index received'  (duration: 136.606502ms)","trace[2040703591] 'applied index is now lower than readState.Index'  (duration: 106.713µs)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:58:34.12012Z","caller":"traceutil/trace.go:171","msg":"trace[944691864] transaction","detail":"{read_only:false; response_revision:24507; number_of_response:1; }","duration":"173.411452ms","start":"2026-03-10T18:58:33.946637Z","end":"2026-03-10T18:58:34.120048Z","steps":["trace[944691864] 'process raft request'  (duration: 173.088015ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:34.120231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.95941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-03-10T18:58:34.120342Z","caller":"traceutil/trace.go:171","msg":"trace[960373213] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24507; }","duration":"137.149894ms","start":"2026-03-10T18:58:33.98317Z","end":"2026-03-10T18:58:34.12032Z","steps":["trace[960373213] 'agreement among raft nodes before linearized reading'  (duration: 136.92355ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:34.277353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.654224ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10211803135608679158 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" mod_revision:24493 > success:<request_put:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" value_size:506 >> failure:<request_range:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" > >>","response":"size:18"}
{"level":"info","ts":"2026-03-10T18:58:34.277519Z","caller":"traceutil/trace.go:171","msg":"trace[545988351] linearizableReadLoop","detail":"{readStateIndex:25376; appliedIndex:25375; }","duration":"157.427104ms","start":"2026-03-10T18:58:34.120069Z","end":"2026-03-10T18:58:34.277496Z","steps":["trace[545988351] 'read index received'  (duration: 19.420331ms)","trace[545988351] 'applied index is now lower than readState.Index'  (duration: 138.004863ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:58:34.277576Z","caller":"traceutil/trace.go:171","msg":"trace[1495537584] transaction","detail":"{read_only:false; response_revision:24508; number_of_response:1; }","duration":"228.170282ms","start":"2026-03-10T18:58:34.049353Z","end":"2026-03-10T18:58:34.277523Z","steps":["trace[1495537584] 'process raft request'  (duration: 90.162239ms)","trace[1495537584] 'compare'  (duration: 137.43551ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-10T18:58:34.277803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.952763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"}
{"level":"info","ts":"2026-03-10T18:58:34.277862Z","caller":"traceutil/trace.go:171","msg":"trace[404322988] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24508; }","duration":"157.047426ms","start":"2026-03-10T18:58:34.120798Z","end":"2026-03-10T18:58:34.277845Z","steps":["trace[404322988] 'agreement among raft nodes before linearized reading'  (duration: 156.844161ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:34.27782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.489719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"}
{"level":"warn","ts":"2026-03-10T18:58:34.277769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.101807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/glance-api-664b5556f7-v2h47.189b8e3ba53c23c2\" ","response":"range_response_count:1 size:2097"}
{"level":"info","ts":"2026-03-10T18:58:34.277931Z","caller":"traceutil/trace.go:171","msg":"trace[263542039] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:24508; }","duration":"237.658073ms","start":"2026-03-10T18:58:34.040244Z","end":"2026-03-10T18:58:34.277902Z","steps":["trace[263542039] 'agreement among raft nodes before linearized reading'  (duration: 237.360537ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:34.278073Z","caller":"traceutil/trace.go:171","msg":"trace[2009408932] range","detail":"{range_begin:/registry/events/openstack/glance-api-664b5556f7-v2h47.189b8e3ba53c23c2; range_end:; response_count:1; response_revision:24508; }","duration":"217.367833ms","start":"2026-03-10T18:58:34.060596Z","end":"2026-03-10T18:58:34.277964Z","steps":["trace[2009408932] 'agreement among raft nodes before linearized reading'  (duration: 216.922434ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:50.309375Z","caller":"traceutil/trace.go:171","msg":"trace[2004080750] transaction","detail":"{read_only:false; response_revision:24632; number_of_response:1; }","duration":"110.253815ms","start":"2026-03-10T18:58:50.199091Z","end":"2026-03-10T18:58:50.309345Z","steps":["trace[2004080750] 'process raft request'  (duration: 110.01719ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:55.664379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.306719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"}
{"level":"info","ts":"2026-03-10T18:58:55.664449Z","caller":"traceutil/trace.go:171","msg":"trace[1062856590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24666; }","duration":"130.423752ms","start":"2026-03-10T18:58:55.534009Z","end":"2026-03-10T18:58:55.664433Z","steps":["trace[1062856590] 'range keys from in-memory index tree'  (duration: 130.042304ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:55.776577Z","caller":"traceutil/trace.go:171","msg":"trace[1146900863] transaction","detail":"{read_only:false; response_revision:24667; number_of_response:1; }","duration":"107.166248ms","start":"2026-03-10T18:58:55.66939Z","end":"2026-03-10T18:58:55.776556Z","steps":["trace[1146900863] 'process raft request'  (duration: 106.999095ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:55.837366Z","caller":"traceutil/trace.go:171","msg":"trace[970632610] transaction","detail":"{read_only:false; response_revision:24668; number_of_response:1; }","duration":"167.850335ms","start":"2026-03-10T18:58:55.669479Z","end":"2026-03-10T18:58:55.837329Z","steps":["trace[970632610] 'process raft request'  (duration: 167.550489ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:56.432044Z","caller":"traceutil/trace.go:171","msg":"trace[455433525] transaction","detail":"{read_only:false; response_revision:24671; number_of_response:1; }","duration":"151.046028ms","start":"2026-03-10T18:58:56.280967Z","end":"2026-03-10T18:58:56.432013Z","steps":["trace[455433525] 'process raft request'  (duration: 150.858494ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:56.727218Z","caller":"traceutil/trace.go:171","msg":"trace[1619242079] linearizableReadLoop","detail":"{readStateIndex:25545; appliedIndex:25543; }","duration":"168.358815ms","start":"2026-03-10T18:58:56.558824Z","end":"2026-03-10T18:58:56.727183Z","steps":["trace[1619242079] 'read index received'  (duration: 115.885784ms)","trace[1619242079] 'applied index is now lower than readState.Index'  (duration: 52.470991ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:58:56.727343Z","caller":"traceutil/trace.go:171","msg":"trace[776373624] transaction","detail":"{read_only:false; response_revision:24672; number_of_response:1; }","duration":"396.940274ms","start":"2026-03-10T18:58:56.330368Z","end":"2026-03-10T18:58:56.727308Z","steps":["trace[776373624] 'process raft request'  (duration: 344.244559ms)","trace[776373624] 'compare'  (duration: 52.028611ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:58:56.727426Z","caller":"traceutil/trace.go:171","msg":"trace[1953601281] transaction","detail":"{read_only:false; response_revision:24673; number_of_response:1; }","duration":"366.613502ms","start":"2026-03-10T18:58:56.360803Z","end":"2026-03-10T18:58:56.727417Z","steps":["trace[1953601281] 'process raft request'  (duration: 366.222644ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:56.727653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:58:56.330339Z","time spent":"397.149779ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/capi-system/controller-leader-election-capi\" mod_revision:24659 > success:<request_put:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" > >"}
{"level":"warn","ts":"2026-03-10T18:58:56.72771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:58:56.360777Z","time spent":"366.820326ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" mod_revision:24660 > success:<request_put:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" value_size:507 >> failure:<request_range:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" > >"}
{"level":"warn","ts":"2026-03-10T18:58:56.72801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.137482ms","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:8"}
{"level":"info","ts":"2026-03-10T18:58:56.728139Z","caller":"traceutil/trace.go:171","msg":"trace[843879567] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:24673; }","duration":"169.345786ms","start":"2026-03-10T18:58:56.558763Z","end":"2026-03-10T18:58:56.728109Z","steps":["trace[843879567] 'agreement among raft nodes before linearized reading'  (duration: 168.692161ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:56.736605Z","caller":"traceutil/trace.go:171","msg":"trace[1433798524] transaction","detail":"{read_only:false; response_revision:24674; number_of_response:1; }","duration":"123.656928ms","start":"2026-03-10T18:58:56.612928Z","end":"2026-03-10T18:58:56.736585Z","steps":["trace[1433798524] 'process raft request'  (duration: 123.461814ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:57.400429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.751766ms","expected-duration":"100ms","prefix":"","request":"header:<ID:10211803135608679559 username:\"kube-apiserver-etcd-client\" auth_revision:1 > lease_grant:<ttl:15-second id:0db79cd8ee2cec86>","response":"size:40"}
{"level":"info","ts":"2026-03-10T18:58:57.40067Z","caller":"traceutil/trace.go:171","msg":"trace[1707618343] linearizableReadLoop","detail":"{readStateIndex:25550; appliedIndex:25548; }","duration":"205.768358ms","start":"2026-03-10T18:58:57.194887Z","end":"2026-03-10T18:58:57.400655Z","steps":["trace[1707618343] 'read index received'  (duration: 60.649025ms)","trace[1707618343] 'applied index is now lower than readState.Index'  (duration: 145.118643ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T18:58:57.400738Z","caller":"traceutil/trace.go:171","msg":"trace[442415871] transaction","detail":"{read_only:false; response_revision:24677; number_of_response:1; }","duration":"224.881501ms","start":"2026-03-10T18:58:57.175841Z","end":"2026-03-10T18:58:57.400722Z","steps":["trace[442415871] 'process raft request'  (duration: 224.715378ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T18:58:57.400837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.958702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"}
{"level":"info","ts":"2026-03-10T18:58:57.400859Z","caller":"traceutil/trace.go:171","msg":"trace[1853396551] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24677; }","duration":"206.027633ms","start":"2026-03-10T18:58:57.194823Z","end":"2026-03-10T18:58:57.40085Z","steps":["trace[1853396551] 'agreement among raft nodes before linearized reading'  (duration: 205.90267ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T18:58:57.564134Z","caller":"traceutil/trace.go:171","msg":"trace[944494159] transaction","detail":"{read_only:false; response_revision:24679; number_of_response:1; }","duration":"158.905895ms","start":"2026-03-10T18:58:57.405195Z","end":"2026-03-10T18:58:57.564101Z","steps":["trace[944494159] 'process raft request'  (duration: 158.693601ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T19:01:54.070504Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23599}
{"level":"info","ts":"2026-03-10T19:01:54.220047Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23599,"took":"143.707331ms","hash":403667763,"current-db-size-bytes":48844800,"current-db-size":"49 MB","current-db-size-in-use-bytes":34549760,"current-db-size-in-use":"34 MB"}
{"level":"info","ts":"2026-03-10T19:01:54.220126Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":403667763,"revision":23599,"compact-revision":20523}
{"level":"info","ts":"2026-03-10T19:02:16.869445Z","caller":"traceutil/trace.go:171","msg":"trace[1965461599] transaction","detail":"{read_only:false; response_revision:26665; number_of_response:1; }","duration":"174.960891ms","start":"2026-03-10T19:02:16.694448Z","end":"2026-03-10T19:02:16.869408Z","steps":["trace[1965461599] 'process raft request'  (duration: 174.700196ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T19:02:16.901134Z","caller":"traceutil/trace.go:171","msg":"trace[753356074] linearizableReadLoop","detail":"{readStateIndex:27587; appliedIndex:27585; }","duration":"200.072312ms","start":"2026-03-10T19:02:16.701019Z","end":"2026-03-10T19:02:16.901091Z","steps":["trace[753356074] 'read index received'  (duration: 168.335481ms)","trace[753356074] 'applied index is now lower than readState.Index'  (duration: 31.735451ms)"],"step_count":2}
{"level":"info","ts":"2026-03-10T19:02:16.901762Z","caller":"traceutil/trace.go:171","msg":"trace[760724426] transaction","detail":"{read_only:false; response_revision:26666; number_of_response:1; }","duration":"202.039924ms","start":"2026-03-10T19:02:16.699705Z","end":"2026-03-10T19:02:16.901745Z","steps":["trace[760724426] 'process raft request'  (duration: 201.183696ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-10T19:02:16.902264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.233447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"}
{"level":"info","ts":"2026-03-10T19:02:16.90283Z","caller":"traceutil/trace.go:171","msg":"trace[1285885884] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26666; }","duration":"201.83999ms","start":"2026-03-10T19:02:16.700965Z","end":"2026-03-10T19:02:16.902805Z","steps":["trace[1285885884] 'agreement among raft nodes before linearized reading'  (duration: 201.133614ms)"],"step_count":1}
{"level":"info","ts":"2026-03-10T19:06:54.076883Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26483}
{"level":"info","ts":"2026-03-10T19:06:54.218144Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26483,"took":"137.026399ms","hash":430445936,"current-db-size-bytes":49696768,"current-db-size":"50 MB","current-db-size-in-use-bytes":29450240,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-03-10T19:06:54.218226Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":430445936,"revision":26483,"compact-revision":23599}
{"level":"info","ts":"2026-03-10T19:07:31.678274Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1082227b3dfb8db7","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-03-10T19:07:31.684028Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003}
{"level":"info","ts":"2026-03-10T19:07:31.684212Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}
{"level":"info","ts":"2026-03-10T19:11:54.083864Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28758}
{"level":"info","ts":"2026-03-10T19:11:54.204162Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28758,"took":"116.122305ms","hash":3246974426,"current-db-size-bytes":49696768,"current-db-size":"50 MB","current-db-size-in-use-bytes":29855744,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-03-10T19:11:54.20426Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3246974426,"revision":28758,"compact-revision":26483}
{"level":"info","ts":"2026-03-10T19:16:54.094355Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30870}
{"level":"info","ts":"2026-03-10T19:16:54.239029Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30870,"took":"141.88055ms","hash":3368996408,"current-db-size-bytes":49696768,"current-db-size":"50 MB","current-db-size-in-use-bytes":28086272,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-03-10T19:16:54.239123Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3368996408,"revision":30870,"compact-revision":28758}
{"level":"info","ts":"2026-03-10T19:18:39.334382Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000883a.wal"}
{"level":"info","ts":"2026-03-10T19:21:54.101138Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33022}
{"level":"info","ts":"2026-03-10T19:21:54.24148Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33022,"took":"136.399694ms","hash":3464739308,"current-db-size-bytes":51003392,"current-db-size":"51 MB","current-db-size-in-use-bytes":29601792,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-03-10T19:21:54.241671Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3464739308,"revision":33022,"compact-revision":30870}
{"level":"info","ts":"2026-03-10T19:26:54.107094Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35104}
{"level":"info","ts":"2026-03-10T19:26:54.233769Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35104,"took":"121.245197ms","hash":1233514122,"current-db-size-bytes":51851264,"current-db-size":"52 MB","current-db-size-in-use-bytes":28135424,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-03-10T19:26:54.233839Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1233514122,"revision":35104,"compact-revision":33022}
{"level":"info","ts":"2026-03-10T19:30:24.470602Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1082227b3dfb8db7","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-03-10T19:30:24.476831Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004}
{"level":"info","ts":"2026-03-10T19:30:24.476945Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}