{"level":"warn","ts":"2026-05-05T13:46:42.666193Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T13:46:42.666285Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.102: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.102:2380","--initial-cluster=instance=https://199.19.213.102:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.102:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.102:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-05T13:46:42.666372Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T13:46:42.666387Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.102:2380"]} {"level":"info","ts":"2026-05-05T13:46:42.66704Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T13:46:42.667726Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.102:2379"]} {"level":"info","ts":"2026-05-05T13:46:42.667815Z","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.102:2380"],"listen-peer-urls":["https://199.19.213.102:2380"],"advertise-client-urls":["https://199.19.213.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.102:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-05T13:46:42.67167Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.583604ms"} {"level":"info","ts":"2026-05-05T13:46:42.681727Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1ee87564e61d159f","cluster-id":"92a92aec402e97c3"} {"level":"info","ts":"2026-05-05T13:46:42.681842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T13:46:42.681866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became follower at term 0"} {"level":"info","ts":"2026-05-05T13:46:42.681879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1ee87564e61d159f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T13:46:42.681886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became follower at term 1"} {"level":"info","ts":"2026-05-05T13:46:42.681941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f switched to configuration voters=(2227159091952555423)"} {"level":"warn","ts":"2026-05-05T13:46:42.691291Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T13:46:42.692807Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T13:46:42.694084Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-05T13:46:42.696112Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1ee87564e61d159f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T13:46:42.696282Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1ee87564e61d159f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T13:46:42.696394Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T13:46:42.697099Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T13:46:42.697132Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T13:46:42.697461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f switched to configuration voters=(2227159091952555423)"} {"level":"info","ts":"2026-05-05T13:46:42.69813Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92a92aec402e97c3","local-member-id":"1ee87564e61d159f","added-peer-id":"1ee87564e61d159f","added-peer-peer-urls":["https://199.19.213.102:2380"]} {"level":"info","ts":"2026-05-05T13:46:42.701271Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T13:46:42.70158Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1ee87564e61d159f","initial-advertise-peer-urls":["https://199.19.213.102:2380"],"listen-peer-urls":["https://199.19.213.102:2380"],"advertise-client-urls":["https://199.19.213.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T13:46:42.701628Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T13:46:42.701751Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.102:2380"} {"level":"info","ts":"2026-05-05T13:46:42.701794Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.102:2380"} {"level":"info","ts":"2026-05-05T13:46:43.582369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T13:46:43.582448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T13:46:43.582475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f received MsgPreVoteResp from 1ee87564e61d159f at term 1"} {"level":"info","ts":"2026-05-05T13:46:43.582495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became candidate at term 2"} {"level":"info","ts":"2026-05-05T13:46:43.582507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f received MsgVoteResp from 1ee87564e61d159f at term 2"} {"level":"info","ts":"2026-05-05T13:46:43.582523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became leader at term 2"} {"level":"info","ts":"2026-05-05T13:46:43.582537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1ee87564e61d159f elected leader 1ee87564e61d159f at term 2"} {"level":"info","ts":"2026-05-05T13:46:43.583493Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T13:46:43.584485Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1ee87564e61d159f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.102:2379]}","request-path":"/0/members/1ee87564e61d159f/attributes","cluster-id":"92a92aec402e97c3","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T13:46:43.584511Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T13:46:43.584685Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T13:46:43.584955Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T13:46:43.584979Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T13:46:43.584983Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92a92aec402e97c3","local-member-id":"1ee87564e61d159f","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T13:46:43.585123Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T13:46:43.58517Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T13:46:43.588076Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T13:46:43.588275Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.102:2379"} {"level":"info","ts":"2026-05-05T13:47:00.604788Z","caller":"traceutil/trace.go:171","msg":"trace[801312557] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"181.015145ms","start":"2026-05-05T13:47:00.423749Z","end":"2026-05-05T13:47:00.604764Z","steps":["trace[801312557] 'process raft request' (duration: 180.83312ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:11.571666Z","caller":"traceutil/trace.go:171","msg":"trace[636009809] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"222.725119ms","start":"2026-05-05T13:47:11.348897Z","end":"2026-05-05T13:47:11.571622Z","steps":["trace[636009809] 'process raft request' (duration: 214.637466ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:11.572269Z","caller":"traceutil/trace.go:171","msg":"trace[397256882] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"220.579591ms","start":"2026-05-05T13:47:11.351665Z","end":"2026-05-05T13:47:11.572244Z","steps":["trace[397256882] 'process raft request' (duration: 220.085289ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:11.572537Z","caller":"traceutil/trace.go:171","msg":"trace[517310776] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"152.810996ms","start":"2026-05-05T13:47:11.419717Z","end":"2026-05-05T13:47:11.572528Z","steps":["trace[517310776] 'process raft request' (duration: 152.274192ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:19.247323Z","caller":"traceutil/trace.go:171","msg":"trace[1092681286] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"144.648451ms","start":"2026-05-05T13:47:19.102647Z","end":"2026-05-05T13:47:19.247295Z","steps":["trace[1092681286] 'process raft request' (duration: 144.490578ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:19.504968Z","caller":"traceutil/trace.go:171","msg":"trace[1497694364] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"250.966268ms","start":"2026-05-05T13:47:19.253966Z","end":"2026-05-05T13:47:19.504932Z","steps":["trace[1497694364] 'read index received' (duration: 246.461761ms)","trace[1497694364] 'applied index is now lower than readState.Index' (duration: 4.503277ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:47:19.505002Z","caller":"traceutil/trace.go:171","msg":"trace[1126174005] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"399.266475ms","start":"2026-05-05T13:47:19.105702Z","end":"2026-05-05T13:47:19.504968Z","steps":["trace[1126174005] 'process raft request' (duration: 395.013993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:19.505278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.294486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-05T13:47:19.505416Z","caller":"traceutil/trace.go:171","msg":"trace[547433715] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:575; }","duration":"251.564444ms","start":"2026-05-05T13:47:19.253825Z","end":"2026-05-05T13:47:19.505389Z","steps":["trace[547433715] 'agreement among raft nodes before linearized reading' (duration: 251.229254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:19.505729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.610694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-05T13:47:19.505797Z","caller":"traceutil/trace.go:171","msg":"trace[1604061060] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:575; }","duration":"230.738117ms","start":"2026-05-05T13:47:19.275043Z","end":"2026-05-05T13:47:19.505781Z","steps":["trace[1604061060] 'agreement among raft nodes before linearized reading' (duration: 230.125712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:19.506438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:19.105667Z","time spent":"399.485801ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:47:19.908262Z","caller":"traceutil/trace.go:171","msg":"trace[1558108928] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"287.794943ms","start":"2026-05-05T13:47:19.620409Z","end":"2026-05-05T13:47:19.908204Z","steps":["trace[1558108928] 'read index received' (duration: 230.226793ms)","trace[1558108928] 'applied index is now lower than readState.Index' (duration: 57.56703ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:47:19.908477Z","caller":"traceutil/trace.go:171","msg":"trace[1566180190] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"338.476192ms","start":"2026-05-05T13:47:19.569956Z","end":"2026-05-05T13:47:19.908432Z","steps":["trace[1566180190] 'process raft request' (duration: 280.620995ms)","trace[1566180190] 'compare' (duration: 57.406345ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:47:19.908637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:19.569905Z","time spent":"338.655836ms","remote":"127.0.0.1:52062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T13:47:19.908688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.265106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-b8h9b\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-05T13:47:19.908769Z","caller":"traceutil/trace.go:171","msg":"trace[1953266852] range","detail":"{range_begin:/registry/pods/kube-system/cilium-b8h9b; range_end:; response_count:1; response_revision:578; }","duration":"288.405789ms","start":"2026-05-05T13:47:19.620346Z","end":"2026-05-05T13:47:19.908752Z","steps":["trace[1953266852] 'agreement among raft nodes before linearized reading' (duration: 288.136292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:19.908918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.014489ms","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-05-05T13:47:19.909049Z","caller":"traceutil/trace.go:171","msg":"trace[735067043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"288.204664ms","start":"2026-05-05T13:47:19.620828Z","end":"2026-05-05T13:47:19.909033Z","steps":["trace[735067043] 'agreement among raft nodes before linearized reading' (duration: 287.71331ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:20.153624Z","caller":"traceutil/trace.go:171","msg":"trace[1551887384] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"233.578522ms","start":"2026-05-05T13:47:19.920019Z","end":"2026-05-05T13:47:20.153597Z","steps":["trace[1551887384] 'read index received' (duration: 233.49784ms)","trace[1551887384] 'applied index is now lower than readState.Index' (duration: 79.722µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:47:20.153849Z","caller":"traceutil/trace.go:171","msg":"trace[984798528] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"240.070163ms","start":"2026-05-05T13:47:19.913751Z","end":"2026-05-05T13:47:20.153822Z","steps":["trace[984798528] 'process raft request' (duration: 239.634692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:20.153968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.852409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:47:20.154092Z","caller":"traceutil/trace.go:171","msg":"trace[1671552571] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:579; }","duration":"234.061076ms","start":"2026-05-05T13:47:19.919991Z","end":"2026-05-05T13:47:20.154052Z","steps":["trace[1671552571] 'agreement among raft nodes before linearized reading' (duration: 233.83838ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:20.165029Z","caller":"traceutil/trace.go:171","msg":"trace[377285946] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"242.211479ms","start":"2026-05-05T13:47:19.9228Z","end":"2026-05-05T13:47:20.165012Z","steps":["trace[377285946] 'process raft request' (duration: 242.107667ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:44.899099Z","caller":"traceutil/trace.go:171","msg":"trace[1220451803] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:791; }","duration":"159.321678ms","start":"2026-05-05T13:47:44.739757Z","end":"2026-05-05T13:47:44.899079Z","steps":["trace[1220451803] 'read index received' (duration: 158.810774ms)","trace[1220451803] 'applied index is now lower than readState.Index' (duration: 509.974µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:47:44.899304Z","caller":"traceutil/trace.go:171","msg":"trace[7466050] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"524.891314ms","start":"2026-05-05T13:47:44.374322Z","end":"2026-05-05T13:47:44.899213Z","steps":["trace[7466050] 'process raft request' (duration: 524.607436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:44.899576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:47:44.37429Z","time spent":"525.127801ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:47:44.899733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.962636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-l7589\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-05T13:47:44.899799Z","caller":"traceutil/trace.go:171","msg":"trace[318477607] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-l7589; range_end:; response_count:1; response_revision:766; }","duration":"160.081957ms","start":"2026-05-05T13:47:44.739702Z","end":"2026-05-05T13:47:44.899784Z","steps":["trace[318477607] 'agreement among raft nodes before linearized reading' (duration: 159.525222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:11.935574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.336211ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-05T13:48:12.260216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.258785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:48:12.260314Z","caller":"traceutil/trace.go:171","msg":"trace[1835394619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1159; }","duration":"173.412251ms","start":"2026-05-05T13:48:12.086878Z","end":"2026-05-05T13:48:12.26029Z","steps":["trace[1835394619] 'range keys from in-memory index tree' (duration: 173.163773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:48.198004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.509765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T13:48:48.198032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.782048ms","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:5"} {"level":"warn","ts":"2026-05-05T13:48:48.198125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.72273ms","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:5"} {"level":"info","ts":"2026-05-05T13:48:48.198129Z","caller":"traceutil/trace.go:171","msg":"trace[1778260122] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1695; }","duration":"175.681609ms","start":"2026-05-05T13:48:48.022424Z","end":"2026-05-05T13:48:48.198106Z","steps":["trace[1778260122] 'range keys from in-memory index tree' (duration: 175.438763ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:48:48.198149Z","caller":"traceutil/trace.go:171","msg":"trace[204263] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1695; }","duration":"162.959722ms","start":"2026-05-05T13:48:48.035164Z","end":"2026-05-05T13:48:48.198123Z","steps":["trace[204263] 'count revisions from in-memory index tree' (duration: 162.657294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:48.19813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.026246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:48:48.198168Z","caller":"traceutil/trace.go:171","msg":"trace[1939425042] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1695; }","duration":"175.770462ms","start":"2026-05-05T13:48:48.022383Z","end":"2026-05-05T13:48:48.198153Z","steps":["trace[1939425042] 'count revisions from in-memory index tree' (duration: 175.685599ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:48:48.198222Z","caller":"traceutil/trace.go:171","msg":"trace[1570971932] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1695; }","duration":"188.17697ms","start":"2026-05-05T13:48:48.010025Z","end":"2026-05-05T13:48:48.198202Z","steps":["trace[1570971932] 'range keys from in-memory index tree' (duration: 187.903772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:48.198041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.800407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T13:48:48.198338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.234462ms","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:5"} {"level":"info","ts":"2026-05-05T13:48:48.198335Z","caller":"traceutil/trace.go:171","msg":"trace[1417750780] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1695; }","duration":"163.115337ms","start":"2026-05-05T13:48:48.03518Z","end":"2026-05-05T13:48:48.198296Z","steps":["trace[1417750780] 'range keys from in-memory index tree' (duration: 162.725336ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:48:48.198407Z","caller":"traceutil/trace.go:171","msg":"trace[1919333522] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1695; }","duration":"188.345265ms","start":"2026-05-05T13:48:48.010043Z","end":"2026-05-05T13:48:48.198388Z","steps":["trace[1919333522] 'count revisions from in-memory index tree' (duration: 188.156839ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:48:48.198435Z","caller":"traceutil/trace.go:171","msg":"trace[320513146] linearizableReadLoop","detail":"{readStateIndex:1763; appliedIndex:1762; }","duration":"150.693407ms","start":"2026-05-05T13:48:48.047728Z","end":"2026-05-05T13:48:48.198421Z","steps":["trace[320513146] 'read index received' (duration: 78.880847ms)","trace[320513146] 'applied index is now lower than readState.Index' (duration: 71.80887ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:48:48.198536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.79905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:48:48.198924Z","caller":"traceutil/trace.go:171","msg":"trace[2056447053] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1696; }","duration":"151.16089ms","start":"2026-05-05T13:48:48.047724Z","end":"2026-05-05T13:48:48.198885Z","steps":["trace[2056447053] 'agreement among raft nodes before linearized reading' (duration: 150.747109ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:48:48.198543Z","caller":"traceutil/trace.go:171","msg":"trace[1909554961] transaction","detail":"{read_only:false; response_revision:1696; number_of_response:1; }","duration":"150.874083ms","start":"2026-05-05T13:48:48.047618Z","end":"2026-05-05T13:48:48.198492Z","steps":["trace[1909554961] 'process raft request' (duration: 78.952959ms)","trace[1909554961] 'compare' (duration: 71.342609ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:48:48.198973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.16641ms","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:5"} {"level":"info","ts":"2026-05-05T13:48:48.199034Z","caller":"traceutil/trace.go:171","msg":"trace[288018098] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1696; }","duration":"151.246774ms","start":"2026-05-05T13:48:48.047768Z","end":"2026-05-05T13:48:48.199015Z","steps":["trace[288018098] 'agreement among raft nodes before linearized reading' (duration: 151.150721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:48.199062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.984354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:48:48.199122Z","caller":"traceutil/trace.go:171","msg":"trace[1118228903] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1696; }","duration":"112.102247ms","start":"2026-05-05T13:48:48.087004Z","end":"2026-05-05T13:48:48.199106Z","steps":["trace[1118228903] 'agreement among raft nodes before linearized reading' (duration: 111.983073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:48.201275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.443163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:48:48.201382Z","caller":"traceutil/trace.go:171","msg":"trace[1286021105] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1696; }","duration":"130.568896ms","start":"2026-05-05T13:48:48.070797Z","end":"2026-05-05T13:48:48.201366Z","steps":["trace[1286021105] 'agreement among raft nodes before linearized reading' (duration: 128.326267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:48.202094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.557203ms","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:5"} {"level":"warn","ts":"2026-05-05T13:48:48.202165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.776048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:48:48.202234Z","caller":"traceutil/trace.go:171","msg":"trace[687302470] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1696; }","duration":"142.700507ms","start":"2026-05-05T13:48:48.059468Z","end":"2026-05-05T13:48:48.202168Z","steps":["trace[687302470] 'agreement among raft nodes before linearized reading' (duration: 142.209243ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:48:48.202276Z","caller":"traceutil/trace.go:171","msg":"trace[292290023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1696; }","duration":"111.867691ms","start":"2026-05-05T13:48:48.090354Z","end":"2026-05-05T13:48:48.202221Z","steps":["trace[292290023] 'agreement among raft nodes before linearized reading' (duration: 108.753678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:48.202568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.485075ms","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:5"} {"level":"info","ts":"2026-05-05T13:48:48.202683Z","caller":"traceutil/trace.go:171","msg":"trace[331570202] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1696; }","duration":"115.65448ms","start":"2026-05-05T13:48:48.086989Z","end":"2026-05-05T13:48:48.202644Z","steps":["trace[331570202] 'agreement among raft nodes before linearized reading' (duration: 115.114136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:48.203553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.118774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:48:48.203817Z","caller":"traceutil/trace.go:171","msg":"trace[915200820] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1696; }","duration":"144.335419ms","start":"2026-05-05T13:48:48.059375Z","end":"2026-05-05T13:48:48.20371Z","steps":["trace[915200820] 'agreement among raft nodes before linearized reading' (duration: 142.297235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:48.203828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.938498ms","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:5"} {"level":"info","ts":"2026-05-05T13:48:48.203894Z","caller":"traceutil/trace.go:171","msg":"trace[1070095050] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1696; }","duration":"133.02167ms","start":"2026-05-05T13:48:48.070853Z","end":"2026-05-05T13:48:48.203875Z","steps":["trace[1070095050] 'agreement among raft nodes before linearized reading' (duration: 131.330325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:55.179036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.588626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-da4fe83c-3e5a-4fff-8204-c1386c043471\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-05-05T13:48:55.179106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.245433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-05T13:48:55.179161Z","caller":"traceutil/trace.go:171","msg":"trace[769474750] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-da4fe83c-3e5a-4fff-8204-c1386c043471; range_end:; response_count:1; response_revision:1812; }","duration":"639.755921ms","start":"2026-05-05T13:48:54.53938Z","end":"2026-05-05T13:48:55.179136Z","steps":["trace[769474750] 'range keys from in-memory index tree' (duration: 639.430132ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:48:55.179205Z","caller":"traceutil/trace.go:171","msg":"trace[200463030] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1812; }","duration":"247.355226ms","start":"2026-05-05T13:48:54.931828Z","end":"2026-05-05T13:48:55.179183Z","steps":["trace[200463030] 'range keys from in-memory index tree' (duration: 247.016257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:55.179529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:48:54.539362Z","time spent":"640.14637ms","remote":"127.0.0.1:51718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-da4fe83c-3e5a-4fff-8204-c1386c043471\" "} {"level":"info","ts":"2026-05-05T13:48:55.191844Z","caller":"traceutil/trace.go:171","msg":"trace[1844042019] linearizableReadLoop","detail":"{readStateIndex:1882; appliedIndex:1881; }","duration":"111.352336ms","start":"2026-05-05T13:48:55.080463Z","end":"2026-05-05T13:48:55.191816Z","steps":["trace[1844042019] 'read index received' (duration: 111.140171ms)","trace[1844042019] 'applied index is now lower than readState.Index' (duration: 211.145µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:48:55.191997Z","caller":"traceutil/trace.go:171","msg":"trace[1920138073] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"122.561613ms","start":"2026-05-05T13:48:55.069415Z","end":"2026-05-05T13:48:55.191977Z","steps":["trace[1920138073] 'process raft request' (duration: 122.245455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:55.19203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.550682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-da4fe83c-3e5a-4fff-8204-c1386c043471\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-05T13:48:55.192243Z","caller":"traceutil/trace.go:171","msg":"trace[748087032] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-da4fe83c-3e5a-4fff-8204-c1386c043471; range_end:; response_count:1; response_revision:1813; }","duration":"111.796458ms","start":"2026-05-05T13:48:55.080419Z","end":"2026-05-05T13:48:55.192215Z","steps":["trace[748087032] 'agreement among raft nodes before linearized reading' (duration: 111.48747ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:48:55.898492Z","caller":"traceutil/trace.go:171","msg":"trace[1764891881] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"103.590831ms","start":"2026-05-05T13:48:55.794877Z","end":"2026-05-05T13:48:55.898468Z","steps":["trace[1764891881] 'process raft request' (duration: 103.455798ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:49:06.745281Z","caller":"traceutil/trace.go:171","msg":"trace[570555091] linearizableReadLoop","detail":"{readStateIndex:1985; appliedIndex:1984; }","duration":"115.910807ms","start":"2026-05-05T13:49:06.629341Z","end":"2026-05-05T13:49:06.745252Z","steps":["trace[570555091] 'read index received' (duration: 115.744202ms)","trace[570555091] 'applied index is now lower than readState.Index' (duration: 165.475µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:49:06.745462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.094612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:49:06.745496Z","caller":"traceutil/trace.go:171","msg":"trace[1192528076] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1912; }","duration":"116.209895ms","start":"2026-05-05T13:49:06.629275Z","end":"2026-05-05T13:49:06.745485Z","steps":["trace[1192528076] 'agreement among raft nodes before linearized reading' (duration: 116.119233ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:49:39.721059Z","caller":"traceutil/trace.go:171","msg":"trace[857399594] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"313.389423ms","start":"2026-05-05T13:49:39.407645Z","end":"2026-05-05T13:49:39.721034Z","steps":["trace[857399594] 'process raft request' (duration: 312.490529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:49:39.721226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:49:39.407622Z","time spent":"313.524106ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:49:40.033678Z","caller":"traceutil/trace.go:171","msg":"trace[1994409847] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"308.099242ms","start":"2026-05-05T13:49:39.725533Z","end":"2026-05-05T13:49:40.033632Z","steps":["trace[1994409847] 'process raft request' (duration: 304.262181ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:49:40.033703Z","caller":"traceutil/trace.go:171","msg":"trace[1337521867] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"307.106948ms","start":"2026-05-05T13:49:39.72656Z","end":"2026-05-05T13:49:40.033667Z","steps":["trace[1337521867] 'process raft request' (duration: 306.930843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:49:40.033864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:49:39.72552Z","time spent":"308.255509ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:49:40.034027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:49:39.726535Z","time spent":"307.363484ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:50:34.699159Z","caller":"traceutil/trace.go:171","msg":"trace[2055662975] transaction","detail":"{read_only:false; response_revision:2576; number_of_response:1; }","duration":"136.475862ms","start":"2026-05-05T13:50:34.562645Z","end":"2026-05-05T13:50:34.699121Z","steps":["trace[2055662975] 'process raft request' (duration: 134.792307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:51:08.65307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.610847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-05T13:51:08.653197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.842874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-05T13:51:08.653329Z","caller":"traceutil/trace.go:171","msg":"trace[1036607285] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2776; }","duration":"161.977779ms","start":"2026-05-05T13:51:08.491303Z","end":"2026-05-05T13:51:08.653281Z","steps":["trace[1036607285] 'range keys from in-memory index tree' (duration: 161.68896ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:51:08.653189Z","caller":"traceutil/trace.go:171","msg":"trace[28165082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2776; }","duration":"169.74337ms","start":"2026-05-05T13:51:08.483427Z","end":"2026-05-05T13:51:08.65317Z","steps":["trace[28165082] 'range keys from in-memory index tree' (duration: 169.457092ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:51:08.909019Z","caller":"traceutil/trace.go:171","msg":"trace[34643158] transaction","detail":"{read_only:false; response_revision:2778; number_of_response:1; }","duration":"251.838285ms","start":"2026-05-05T13:51:08.65715Z","end":"2026-05-05T13:51:08.908989Z","steps":["trace[34643158] 'process raft request' (duration: 251.791423ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:51:08.909062Z","caller":"traceutil/trace.go:171","msg":"trace[512035398] transaction","detail":"{read_only:false; response_revision:2777; number_of_response:1; }","duration":"251.895077ms","start":"2026-05-05T13:51:08.65713Z","end":"2026-05-05T13:51:08.909025Z","steps":["trace[512035398] 'process raft request' (duration: 251.674291ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:51:09.12094Z","caller":"traceutil/trace.go:171","msg":"trace[640345074] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"463.741523ms","start":"2026-05-05T13:51:08.657178Z","end":"2026-05-05T13:51:09.120919Z","steps":["trace[640345074] 'process raft request' (duration: 463.350532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:51:09.121077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:51:08.657158Z","time spent":"463.842965ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:51:09.121183Z","caller":"traceutil/trace.go:171","msg":"trace[1715937713] transaction","detail":"{read_only:false; response_revision:2780; number_of_response:1; }","duration":"463.325431ms","start":"2026-05-05T13:51:08.65781Z","end":"2026-05-05T13:51:09.121135Z","steps":["trace[1715937713] 'process raft request' (duration: 463.079153ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:51:09.121278Z","caller":"traceutil/trace.go:171","msg":"trace[683330135] linearizableReadLoop","detail":"{readStateIndex:2888; appliedIndex:2883; }","duration":"301.463775ms","start":"2026-05-05T13:51:08.81979Z","end":"2026-05-05T13:51:09.121254Z","steps":["trace[683330135] 'read index received' (duration: 89.088844ms)","trace[683330135] 'applied index is now lower than readState.Index' (duration: 212.374111ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:51:09.121322Z","caller":"traceutil/trace.go:171","msg":"trace[847968806] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"301.385533ms","start":"2026-05-05T13:51:08.819896Z","end":"2026-05-05T13:51:09.121281Z","steps":["trace[847968806] 'process raft request' (duration: 301.292061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:51:09.121381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:51:08.657793Z","time spent":"463.461416ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:51:09.121447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:51:08.819881Z","time spent":"301.507137ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:51:09.121328Z","caller":"traceutil/trace.go:171","msg":"trace[589532248] transaction","detail":"{read_only:false; response_revision:2781; number_of_response:1; }","duration":"461.292155ms","start":"2026-05-05T13:51:08.659991Z","end":"2026-05-05T13:51:09.121283Z","steps":["trace[589532248] 'process raft request' (duration: 461.061719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:51:09.121535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:51:08.659982Z","time spent":"461.516242ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:51:09.121559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.752294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-05T13:51:09.121599Z","caller":"traceutil/trace.go:171","msg":"trace[1774335878] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2782; }","duration":"301.845776ms","start":"2026-05-05T13:51:08.819742Z","end":"2026-05-05T13:51:09.121588Z","steps":["trace[1774335878] 'agreement among raft nodes before linearized reading' (duration: 301.686411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:51:09.121628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:51:08.819723Z","time spent":"301.895757ms","remote":"127.0.0.1:51730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-05-05T13:53:34.985997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.672184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T13:53:34.986101Z","caller":"traceutil/trace.go:171","msg":"trace[725688313] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:3748; }","duration":"109.851649ms","start":"2026-05-05T13:53:34.876231Z","end":"2026-05-05T13:53:34.986083Z","steps":["trace[725688313] 'count revisions from in-memory index tree' (duration: 109.490719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:53:45.461343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.108742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T13:53:45.461631Z","caller":"traceutil/trace.go:171","msg":"trace[1302231633] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:3799; }","duration":"330.492583ms","start":"2026-05-05T13:53:45.131112Z","end":"2026-05-05T13:53:45.461605Z","steps":["trace[1302231633] 'count revisions from in-memory index tree' (duration: 329.99267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:53:45.461892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:53:45.131087Z","time spent":"330.643607ms","remote":"127.0.0.1:51698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":22,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-05-05T13:53:45.631104Z","caller":"traceutil/trace.go:171","msg":"trace[283837268] linearizableReadLoop","detail":"{readStateIndex:3944; appliedIndex:3943; }","duration":"114.724121ms","start":"2026-05-05T13:53:45.516343Z","end":"2026-05-05T13:53:45.631067Z","steps":["trace[283837268] 'read index received' (duration: 114.556157ms)","trace[283837268] 'applied index is now lower than readState.Index' (duration: 166.394µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:53:45.631166Z","caller":"traceutil/trace.go:171","msg":"trace[1255829859] transaction","detail":"{read_only:false; response_revision:3800; number_of_response:1; }","duration":"307.718741ms","start":"2026-05-05T13:53:45.323399Z","end":"2026-05-05T13:53:45.631118Z","steps":["trace[1255829859] 'process raft request' (duration: 307.485165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:53:45.631494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:53:45.323344Z","time spent":"307.979429ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:53:45.631734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.36372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nms2w\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-05T13:53:45.631793Z","caller":"traceutil/trace.go:171","msg":"trace[904815108] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nms2w; range_end:; response_count:1; response_revision:3800; }","duration":"115.491332ms","start":"2026-05-05T13:53:45.516288Z","end":"2026-05-05T13:53:45.631779Z","steps":["trace[904815108] 'agreement among raft nodes before linearized reading' (duration: 114.919267ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:36.408962Z","caller":"traceutil/trace.go:171","msg":"trace[1053760191] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4159; }","duration":"145.960561ms","start":"2026-05-05T13:54:36.262979Z","end":"2026-05-05T13:54:36.408939Z","steps":["trace[1053760191] 'process raft request' (duration: 134.635233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:36.648229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.255733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.102\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T13:54:36.64829Z","caller":"traceutil/trace.go:171","msg":"trace[1748771421] range","detail":"{range_begin:/registry/masterleases/199.19.213.102; range_end:; response_count:1; response_revision:4161; }","duration":"130.368077ms","start":"2026-05-05T13:54:36.517908Z","end":"2026-05-05T13:54:36.648276Z","steps":["trace[1748771421] 'range keys from in-memory index tree' (duration: 130.092299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:37.212696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.697965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:54:37.212827Z","caller":"traceutil/trace.go:171","msg":"trace[1283837930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4164; }","duration":"123.837109ms","start":"2026-05-05T13:54:37.08896Z","end":"2026-05-05T13:54:37.212797Z","steps":["trace[1283837930] 'range keys from in-memory index tree' (duration: 123.615093ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:02.553581Z","caller":"traceutil/trace.go:171","msg":"trace[311219881] linearizableReadLoop","detail":"{readStateIndex:4905; appliedIndex:4903; }","duration":"170.030304ms","start":"2026-05-05T13:55:02.383523Z","end":"2026-05-05T13:55:02.553554Z","steps":["trace[311219881] 'read index received' (duration: 3.826574ms)","trace[311219881] 'applied index is now lower than readState.Index' (duration: 166.20248ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:55:02.553676Z","caller":"traceutil/trace.go:171","msg":"trace[1499292988] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"171.670228ms","start":"2026-05-05T13:55:02.381962Z","end":"2026-05-05T13:55:02.553632Z","steps":["trace[1499292988] 'process raft request' (duration: 171.38726ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:02.553709Z","caller":"traceutil/trace.go:171","msg":"trace[1949806156] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"167.569277ms","start":"2026-05-05T13:55:02.386107Z","end":"2026-05-05T13:55:02.553677Z","steps":["trace[1949806156] 'process raft request' (duration: 167.390413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:02.554062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.500787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-05-05T13:55:02.554183Z","caller":"traceutil/trace.go:171","msg":"trace[2012909357] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4725; }","duration":"170.686382ms","start":"2026-05-05T13:55:02.383471Z","end":"2026-05-05T13:55:02.554157Z","steps":["trace[2012909357] 'agreement among raft nodes before linearized reading' (duration: 170.205288ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:03.181678Z","caller":"traceutil/trace.go:171","msg":"trace[154835234] linearizableReadLoop","detail":"{readStateIndex:4932; appliedIndex:4931; }","duration":"130.809707ms","start":"2026-05-05T13:55:03.05084Z","end":"2026-05-05T13:55:03.181649Z","steps":["trace[154835234] 'read index received' (duration: 129.878672ms)","trace[154835234] 'applied index is now lower than readState.Index' (duration: 929.795µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:55:03.181738Z","caller":"traceutil/trace.go:171","msg":"trace[344819879] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"171.688439ms","start":"2026-05-05T13:55:03.010012Z","end":"2026-05-05T13:55:03.181701Z","steps":["trace[344819879] 'process raft request' (duration: 170.757783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:03.181917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.064464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2d9e208b-c9af-4ba4-a09a-facb76261180\" ","response":"range_response_count:1 size:3891"} {"level":"info","ts":"2026-05-05T13:55:03.181963Z","caller":"traceutil/trace.go:171","msg":"trace[1487811721] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2d9e208b-c9af-4ba4-a09a-facb76261180; range_end:; response_count:1; response_revision:4751; }","duration":"131.196247ms","start":"2026-05-05T13:55:03.050753Z","end":"2026-05-05T13:55:03.181949Z","steps":["trace[1487811721] 'agreement among raft nodes before linearized reading' (duration: 131.008382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:05.113352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.414498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-05-05T13:55:05.113487Z","caller":"traceutil/trace.go:171","msg":"trace[1147138150] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4800; }","duration":"172.626445ms","start":"2026-05-05T13:55:04.940833Z","end":"2026-05-05T13:55:05.11346Z","steps":["trace[1147138150] 'range keys from in-memory index tree' (duration: 172.241133ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:06.222443Z","caller":"traceutil/trace.go:171","msg":"trace[1465532557] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"326.347765ms","start":"2026-05-05T13:55:05.896074Z","end":"2026-05-05T13:55:06.222422Z","steps":["trace[1465532557] 'process raft request' (duration: 326.081098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:06.222641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:55:05.896061Z","time spent":"326.499039ms","remote":"127.0.0.1:51718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9815,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:55:10.086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.72232ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:55:10.086109Z","caller":"traceutil/trace.go:171","msg":"trace[1854898862] linearizableReadLoop","detail":"{readStateIndex:5175; appliedIndex:5174; }","duration":"676.539948ms","start":"2026-05-05T13:55:09.409551Z","end":"2026-05-05T13:55:10.08609Z","steps":["trace[1854898862] 'read index received' (duration: 411.592723ms)","trace[1854898862] 'applied index is now lower than readState.Index' (duration: 264.945805ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:55:10.086159Z","caller":"traceutil/trace.go:171","msg":"trace[148794061] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"704.9395ms","start":"2026-05-05T13:55:09.381165Z","end":"2026-05-05T13:55:10.086104Z","steps":["trace[148794061] 'process raft request' (duration: 439.999705ms)","trace[148794061] 'compare' (duration: 264.430481ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:55:10.086283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.742094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-5p2g9-tls\" ","response":"range_response_count:1 size:2184"} {"level":"info","ts":"2026-05-05T13:55:10.086323Z","caller":"traceutil/trace.go:171","msg":"trace[1702312320] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-5p2g9-tls; range_end:; response_count:1; response_revision:4985; }","duration":"676.821776ms","start":"2026-05-05T13:55:09.409491Z","end":"2026-05-05T13:55:10.086313Z","steps":["trace[1702312320] 'agreement among raft nodes before linearized reading' (duration: 676.671651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:10.086362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:55:09.409476Z","time spent":"676.877437ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":2207,"request content":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-5p2g9-tls\" "} {"level":"warn","ts":"2026-05-05T13:55:10.086364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:55:09.381136Z","time spent":"705.125635ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:55:10.127389Z","caller":"traceutil/trace.go:171","msg":"trace[1094651555] transaction","detail":"{read_only:false; response_revision:4986; number_of_response:1; }","duration":"689.434709ms","start":"2026-05-05T13:55:09.437929Z","end":"2026-05-05T13:55:10.127363Z","steps":["trace[1094651555] 'process raft request' (duration: 689.327746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:10.127471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:55:09.437907Z","time spent":"689.52495ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:55:10.12773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.048792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1dabb779-d60d-448a-a2c4-b1a61e7cb96d\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-05T13:55:10.127794Z","caller":"traceutil/trace.go:171","msg":"trace[857621277] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1dabb779-d60d-448a-a2c4-b1a61e7cb96d; range_end:; response_count:1; response_revision:4986; }","duration":"192.163326ms","start":"2026-05-05T13:55:09.935613Z","end":"2026-05-05T13:55:10.127777Z","steps":["trace[857621277] 'agreement among raft nodes before linearized reading' (duration: 191.991021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:10.127821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.189186ms","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-05-05T13:55:10.127916Z","caller":"traceutil/trace.go:171","msg":"trace[2065251191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4986; }","duration":"684.34005ms","start":"2026-05-05T13:55:09.443553Z","end":"2026-05-05T13:55:10.127893Z","steps":["trace[2065251191] 'agreement among raft nodes before linearized reading' (duration: 684.039672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:10.127958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:55:09.443527Z","time spent":"684.422632ms","remote":"127.0.0.1:51796","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-05-05T13:55:10.128034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.203018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1dabb779-d60d-448a-a2c4-b1a61e7cb96d\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-05T13:55:10.128088Z","caller":"traceutil/trace.go:171","msg":"trace[1468231854] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1dabb779-d60d-448a-a2c4-b1a61e7cb96d; range_end:; response_count:1; response_revision:4986; }","duration":"605.330871ms","start":"2026-05-05T13:55:09.522743Z","end":"2026-05-05T13:55:10.128074Z","steps":["trace[1468231854] 'agreement among raft nodes before linearized reading' (duration: 605.059034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:10.128124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:55:09.522711Z","time spent":"605.406134ms","remote":"127.0.0.1:51718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3624,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1dabb779-d60d-448a-a2c4-b1a61e7cb96d\" "} {"level":"info","ts":"2026-05-05T13:55:10.292721Z","caller":"traceutil/trace.go:171","msg":"trace[155251024] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"107.666748ms","start":"2026-05-05T13:55:10.185032Z","end":"2026-05-05T13:55:10.292699Z","steps":["trace[155251024] 'process raft request' (duration: 107.540774ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:10.995621Z","caller":"traceutil/trace.go:171","msg":"trace[1498105596] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"345.982849ms","start":"2026-05-05T13:55:10.649611Z","end":"2026-05-05T13:55:10.995594Z","steps":["trace[1498105596] 'process raft request' (duration: 345.775963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:10.995811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:55:10.649557Z","time spent":"346.162843ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:55:11.09557Z","caller":"traceutil/trace.go:171","msg":"trace[701817969] linearizableReadLoop","detail":"{readStateIndex:5184; appliedIndex:5182; }","duration":"325.893003ms","start":"2026-05-05T13:55:10.769654Z","end":"2026-05-05T13:55:11.095547Z","steps":["trace[701817969] 'read index received' (duration: 225.77521ms)","trace[701817969] 'applied index is now lower than readState.Index' (duration: 100.116933ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:55:11.095623Z","caller":"traceutil/trace.go:171","msg":"trace[1455333206] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"445.641219ms","start":"2026-05-05T13:55:10.649951Z","end":"2026-05-05T13:55:11.095592Z","steps":["trace[1455333206] 'process raft request' (duration: 445.429473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:11.09576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.087087ms","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":"warn","ts":"2026-05-05T13:55:11.09578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:55:10.649932Z","time spent":"445.755692ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:55:11.095813Z","caller":"traceutil/trace.go:171","msg":"trace[1685394317] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4994; }","duration":"326.19129ms","start":"2026-05-05T13:55:10.769612Z","end":"2026-05-05T13:55:11.095803Z","steps":["trace[1685394317] 'agreement among raft nodes before linearized reading' (duration: 326.009605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:11.095845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:55:10.769591Z","time spent":"326.246252ms","remote":"127.0.0.1:51796","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":"info","ts":"2026-05-05T13:55:18.136969Z","caller":"traceutil/trace.go:171","msg":"trace[355137326] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"110.30345ms","start":"2026-05-05T13:55:18.026631Z","end":"2026-05-05T13:55:18.136934Z","steps":["trace[355137326] 'process raft request' (duration: 109.783796ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:18.383317Z","caller":"traceutil/trace.go:171","msg":"trace[725391256] linearizableReadLoop","detail":"{readStateIndex:5333; appliedIndex:5329; }","duration":"185.781772ms","start":"2026-05-05T13:55:18.1975Z","end":"2026-05-05T13:55:18.383282Z","steps":["trace[725391256] 'read index received' (duration: 14.635538ms)","trace[725391256] 'applied index is now lower than readState.Index' (duration: 171.144974ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:55:18.383404Z","caller":"traceutil/trace.go:171","msg":"trace[1746212387] transaction","detail":"{read_only:false; response_revision:5138; number_of_response:1; }","duration":"191.627621ms","start":"2026-05-05T13:55:18.19177Z","end":"2026-05-05T13:55:18.383398Z","steps":["trace[1746212387] 'process raft request' (duration: 191.455417ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:18.383518Z","caller":"traceutil/trace.go:171","msg":"trace[1347154500] transaction","detail":"{read_only:false; response_revision:5136; number_of_response:1; }","duration":"193.105681ms","start":"2026-05-05T13:55:18.190404Z","end":"2026-05-05T13:55:18.38351Z","steps":["trace[1347154500] 'process raft request' (duration: 177.708573ms)","trace[1347154500] 'compare' (duration: 14.816143ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:55:18.383348Z","caller":"traceutil/trace.go:171","msg":"trace[930279204] transaction","detail":"{read_only:false; response_revision:5137; number_of_response:1; }","duration":"192.486195ms","start":"2026-05-05T13:55:18.190839Z","end":"2026-05-05T13:55:18.383325Z","steps":["trace[930279204] 'process raft request' (duration: 192.295561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:18.383792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.275736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:9 size:368407"} {"level":"info","ts":"2026-05-05T13:55:18.383851Z","caller":"traceutil/trace.go:171","msg":"trace[629674680] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:9; response_revision:5138; }","duration":"186.387299ms","start":"2026-05-05T13:55:18.197446Z","end":"2026-05-05T13:55:18.383833Z","steps":["trace[629674680] 'agreement among raft nodes before linearized reading' (duration: 186.001129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:18.385584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.790517ms","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-05-05T13:55:18.385699Z","caller":"traceutil/trace.go:171","msg":"trace[1145155745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5139; }","duration":"180.949641ms","start":"2026-05-05T13:55:18.204724Z","end":"2026-05-05T13:55:18.385674Z","steps":["trace[1145155745] 'agreement among raft nodes before linearized reading' (duration: 180.082308ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:19.275373Z","caller":"traceutil/trace.go:171","msg":"trace[2017123797] transaction","detail":"{read_only:false; response_revision:5179; number_of_response:1; }","duration":"113.671151ms","start":"2026-05-05T13:55:19.161681Z","end":"2026-05-05T13:55:19.275352Z","steps":["trace[2017123797] 'process raft request' (duration: 113.529967ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:27.915858Z","caller":"traceutil/trace.go:171","msg":"trace[1536686320] linearizableReadLoop","detail":"{readStateIndex:5556; appliedIndex:5555; }","duration":"175.502574ms","start":"2026-05-05T13:55:27.740336Z","end":"2026-05-05T13:55:27.915839Z","steps":["trace[1536686320] 'read index received' (duration: 175.253616ms)","trace[1536686320] 'applied index is now lower than readState.Index' (duration: 248.057µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:55:27.915944Z","caller":"traceutil/trace.go:171","msg":"trace[2060648178] transaction","detail":"{read_only:false; response_revision:5358; number_of_response:1; }","duration":"187.131118ms","start":"2026-05-05T13:55:27.728784Z","end":"2026-05-05T13:55:27.915916Z","steps":["trace[2060648178] 'process raft request' (duration: 186.869531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:27.916133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.78233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ce47bb46-a19f-4583-939b-4d518f7222f0\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-05T13:55:27.916159Z","caller":"traceutil/trace.go:171","msg":"trace[952748607] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ce47bb46-a19f-4583-939b-4d518f7222f0; range_end:; response_count:1; response_revision:5358; }","duration":"175.868774ms","start":"2026-05-05T13:55:27.740282Z","end":"2026-05-05T13:55:27.916151Z","steps":["trace[952748607] 'agreement among raft nodes before linearized reading' (duration: 175.633717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:28.195845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.99653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:55:28.19593Z","caller":"traceutil/trace.go:171","msg":"trace[955795149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5358; }","duration":"107.130774ms","start":"2026-05-05T13:55:28.088781Z","end":"2026-05-05T13:55:28.195912Z","steps":["trace[955795149] 'range keys from in-memory index tree' (duration: 106.892296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:28.638847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.691914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:55:28.638977Z","caller":"traceutil/trace.go:171","msg":"trace[251754188] linearizableReadLoop","detail":"{readStateIndex:5559; appliedIndex:5558; }","duration":"205.694744ms","start":"2026-05-05T13:55:28.433262Z","end":"2026-05-05T13:55:28.638956Z","steps":["trace[251754188] 'read index received' (duration: 31.720364ms)","trace[251754188] 'applied index is now lower than readState.Index' (duration: 173.97277ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:55:28.639048Z","caller":"traceutil/trace.go:171","msg":"trace[998532836] transaction","detail":"{read_only:false; response_revision:5361; number_of_response:1; }","duration":"214.045401ms","start":"2026-05-05T13:55:28.424972Z","end":"2026-05-05T13:55:28.639017Z","steps":["trace[998532836] 'process raft request' (duration: 40.06561ms)","trace[998532836] 'compare' (duration: 173.284262ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:55:28.639217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.955511ms","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-05-05T13:55:28.639266Z","caller":"traceutil/trace.go:171","msg":"trace[947666818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5361; }","duration":"206.032932ms","start":"2026-05-05T13:55:28.43322Z","end":"2026-05-05T13:55:28.639253Z","steps":["trace[947666818] 'agreement among raft nodes before linearized reading' (duration: 205.850147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:28.639325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.661996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-05T13:55:28.639412Z","caller":"traceutil/trace.go:171","msg":"trace[1365599944] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5361; }","duration":"136.742409ms","start":"2026-05-05T13:55:28.502646Z","end":"2026-05-05T13:55:28.639388Z","steps":["trace[1365599944] 'agreement among raft nodes before linearized reading' (duration: 136.579315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:28.639325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.81887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nms2w\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-05-05T13:55:28.639468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.987418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T13:55:28.639498Z","caller":"traceutil/trace.go:171","msg":"trace[2034338947] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nms2w; range_end:; response_count:1; response_revision:5361; }","duration":"205.045966ms","start":"2026-05-05T13:55:28.434439Z","end":"2026-05-05T13:55:28.639484Z","steps":["trace[2034338947] 'agreement among raft nodes before linearized reading' (duration: 204.709047ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:28.63952Z","caller":"traceutil/trace.go:171","msg":"trace[247409931] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5361; }","duration":"143.06973ms","start":"2026-05-05T13:55:28.496432Z","end":"2026-05-05T13:55:28.639502Z","steps":["trace[247409931] 'agreement among raft nodes before linearized reading' (duration: 142.945767ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:28.80586Z","caller":"traceutil/trace.go:171","msg":"trace[1330034242] transaction","detail":"{read_only:false; response_revision:5362; number_of_response:1; }","duration":"161.922564ms","start":"2026-05-05T13:55:28.643911Z","end":"2026-05-05T13:55:28.805834Z","steps":["trace[1330034242] 'process raft request' (duration: 160.72012ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:28.805927Z","caller":"traceutil/trace.go:171","msg":"trace[340945263] transaction","detail":"{read_only:false; response_revision:5363; number_of_response:1; }","duration":"161.978815ms","start":"2026-05-05T13:55:28.643922Z","end":"2026-05-05T13:55:28.805901Z","steps":["trace[340945263] 'process raft request' (duration: 161.830141ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:28.805986Z","caller":"traceutil/trace.go:171","msg":"trace[498827923] transaction","detail":"{read_only:false; response_revision:5364; number_of_response:1; }","duration":"161.830811ms","start":"2026-05-05T13:55:28.644125Z","end":"2026-05-05T13:55:28.805956Z","steps":["trace[498827923] 'process raft request' (duration: 161.722778ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:30.458283Z","caller":"traceutil/trace.go:171","msg":"trace[981985096] transaction","detail":"{read_only:false; response_revision:5377; number_of_response:1; }","duration":"131.049613ms","start":"2026-05-05T13:55:30.327208Z","end":"2026-05-05T13:55:30.458258Z","steps":["trace[981985096] 'process raft request' (duration: 130.89854ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:38.805697Z","caller":"traceutil/trace.go:171","msg":"trace[1211290785] linearizableReadLoop","detail":"{readStateIndex:5723; appliedIndex:5722; }","duration":"145.100526ms","start":"2026-05-05T13:55:38.660573Z","end":"2026-05-05T13:55:38.805674Z","steps":["trace[1211290785] 'read index received' (duration: 144.971593ms)","trace[1211290785] 'applied index is now lower than readState.Index' (duration: 127.993µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:55:38.805743Z","caller":"traceutil/trace.go:171","msg":"trace[553120820] transaction","detail":"{read_only:false; response_revision:5519; number_of_response:1; }","duration":"301.667373ms","start":"2026-05-05T13:55:38.504042Z","end":"2026-05-05T13:55:38.805709Z","steps":["trace[553120820] 'process raft request' (duration: 301.494569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:38.805881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:55:38.504015Z","time spent":"301.793906ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:55:38.806003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.359712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:55:38.806127Z","caller":"traceutil/trace.go:171","msg":"trace[1837367350] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:5519; }","duration":"145.565318ms","start":"2026-05-05T13:55:38.660535Z","end":"2026-05-05T13:55:38.8061Z","steps":["trace[1837367350] 'agreement among raft nodes before linearized reading' (duration: 145.292942ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:39.081801Z","caller":"traceutil/trace.go:171","msg":"trace[111917529] linearizableReadLoop","detail":"{readStateIndex:5724; appliedIndex:5723; }","duration":"275.980725ms","start":"2026-05-05T13:55:38.805782Z","end":"2026-05-05T13:55:39.081763Z","steps":["trace[111917529] 'read index received' (duration: 269.605552ms)","trace[111917529] 'applied index is now lower than readState.Index' (duration: 6.372823ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:55:39.082001Z","caller":"traceutil/trace.go:171","msg":"trace[624177392] transaction","detail":"{read_only:false; response_revision:5520; number_of_response:1; }","duration":"396.136203ms","start":"2026-05-05T13:55:38.685831Z","end":"2026-05-05T13:55:39.081967Z","steps":["trace[624177392] 'process raft request' (duration: 389.494642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:39.082201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:55:38.685803Z","time spent":"396.282277ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:55:39.082269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.198961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nms2w\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-05T13:55:39.082326Z","caller":"traceutil/trace.go:171","msg":"trace[634089287] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nms2w; range_end:; response_count:1; response_revision:5520; }","duration":"383.303793ms","start":"2026-05-05T13:55:38.699009Z","end":"2026-05-05T13:55:39.082313Z","steps":["trace[634089287] 'agreement among raft nodes before linearized reading' (duration: 383.044375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:39.082378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:55:38.698995Z","time spent":"383.366965ms","remote":"127.0.0.1:51718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-nms2w\" "} {"level":"warn","ts":"2026-05-05T13:55:39.083002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.302362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-05-05T13:55:39.083051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.215753ms","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-05-05T13:55:39.083096Z","caller":"traceutil/trace.go:171","msg":"trace[124825064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5520; }","duration":"190.315875ms","start":"2026-05-05T13:55:38.892769Z","end":"2026-05-05T13:55:39.083085Z","steps":["trace[124825064] 'agreement among raft nodes before linearized reading' (duration: 190.14657ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:39.083091Z","caller":"traceutil/trace.go:171","msg":"trace[1808011247] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5520; }","duration":"191.453557ms","start":"2026-05-05T13:55:38.891617Z","end":"2026-05-05T13:55:39.08307Z","steps":["trace[1808011247] 'agreement among raft nodes before linearized reading' (duration: 191.237081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:39.083192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.092986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-05T13:55:39.083241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.399118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17206"} {"level":"info","ts":"2026-05-05T13:55:39.0833Z","caller":"traceutil/trace.go:171","msg":"trace[46420307] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5520; }","duration":"188.251819ms","start":"2026-05-05T13:55:38.895025Z","end":"2026-05-05T13:55:39.083276Z","steps":["trace[46420307] 'agreement among raft nodes before linearized reading' (duration: 187.883879ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:39.08337Z","caller":"traceutil/trace.go:171","msg":"trace[1189427161] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5520; }","duration":"158.595702ms","start":"2026-05-05T13:55:38.924745Z","end":"2026-05-05T13:55:39.083341Z","steps":["trace[1189427161] 'agreement among raft nodes before linearized reading' (duration: 158.12495ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:39.461182Z","caller":"traceutil/trace.go:171","msg":"trace[696936875] transaction","detail":"{read_only:false; response_revision:5527; number_of_response:1; }","duration":"126.873761ms","start":"2026-05-05T13:55:39.334274Z","end":"2026-05-05T13:55:39.461148Z","steps":["trace[696936875] 'process raft request' (duration: 39.411981ms)","trace[696936875] 'compare' (duration: 87.306685ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:55:39.484004Z","caller":"traceutil/trace.go:171","msg":"trace[1002198969] transaction","detail":"{read_only:false; response_revision:5529; number_of_response:1; }","duration":"148.671133ms","start":"2026-05-05T13:55:39.335307Z","end":"2026-05-05T13:55:39.483978Z","steps":["trace[1002198969] 'process raft request' (duration: 148.451717ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:39.483999Z","caller":"traceutil/trace.go:171","msg":"trace[1832148772] transaction","detail":"{read_only:false; response_revision:5528; number_of_response:1; }","duration":"149.404973ms","start":"2026-05-05T13:55:39.334545Z","end":"2026-05-05T13:55:39.48395Z","steps":["trace[1832148772] 'process raft request' (duration: 149.024363ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:39.484176Z","caller":"traceutil/trace.go:171","msg":"trace[301962993] transaction","detail":"{read_only:false; response_revision:5532; number_of_response:1; }","duration":"138.813485ms","start":"2026-05-05T13:55:39.34533Z","end":"2026-05-05T13:55:39.484144Z","steps":["trace[301962993] 'process raft request' (duration: 138.708892ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:39.484222Z","caller":"traceutil/trace.go:171","msg":"trace[1836733276] transaction","detail":"{read_only:false; response_revision:5531; number_of_response:1; }","duration":"147.775329ms","start":"2026-05-05T13:55:39.336417Z","end":"2026-05-05T13:55:39.484192Z","steps":["trace[1836733276] 'process raft request' (duration: 147.539522ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:39.484174Z","caller":"traceutil/trace.go:171","msg":"trace[76813287] transaction","detail":"{read_only:false; response_revision:5530; number_of_response:1; }","duration":"148.433326ms","start":"2026-05-05T13:55:39.335686Z","end":"2026-05-05T13:55:39.48412Z","steps":["trace[76813287] 'process raft request' (duration: 148.155078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:55:39.731766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.779266ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:55:39.73201Z","caller":"traceutil/trace.go:171","msg":"trace[1878719068] transaction","detail":"{read_only:false; response_revision:5536; number_of_response:1; }","duration":"240.15391ms","start":"2026-05-05T13:55:39.491835Z","end":"2026-05-05T13:55:39.731989Z","steps":["trace[1878719068] 'process raft request' (duration: 240.102259ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:39.732261Z","caller":"traceutil/trace.go:171","msg":"trace[1155972919] transaction","detail":"{read_only:false; response_revision:5535; number_of_response:1; }","duration":"240.424449ms","start":"2026-05-05T13:55:39.491826Z","end":"2026-05-05T13:55:39.732251Z","steps":["trace[1155972919] 'process raft request' (duration: 240.040348ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:39.732291Z","caller":"traceutil/trace.go:171","msg":"trace[211162180] transaction","detail":"{read_only:false; response_revision:5534; number_of_response:1; }","duration":"241.247931ms","start":"2026-05-05T13:55:39.491003Z","end":"2026-05-05T13:55:39.732251Z","steps":["trace[211162180] 'process raft request' (duration: 109.882418ms)","trace[211162180] 'compare' (duration: 130.625062ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:55:39.773459Z","caller":"traceutil/trace.go:171","msg":"trace[1987127490] transaction","detail":"{read_only:false; response_revision:5537; number_of_response:1; }","duration":"280.632162ms","start":"2026-05-05T13:55:39.492801Z","end":"2026-05-05T13:55:39.773433Z","steps":["trace[1987127490] 'process raft request' (duration: 280.381136ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:39.773846Z","caller":"traceutil/trace.go:171","msg":"trace[298093496] transaction","detail":"{read_only:false; response_revision:5538; number_of_response:1; }","duration":"277.757923ms","start":"2026-05-05T13:55:39.496073Z","end":"2026-05-05T13:55:39.773831Z","steps":["trace[298093496] 'process raft request' (duration: 277.302931ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:39.774124Z","caller":"traceutil/trace.go:171","msg":"trace[1328990572] transaction","detail":"{read_only:false; response_revision:5539; number_of_response:1; }","duration":"271.406831ms","start":"2026-05-05T13:55:39.502704Z","end":"2026-05-05T13:55:39.774111Z","steps":["trace[1328990572] 'process raft request' (duration: 270.912717ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:39.901935Z","caller":"traceutil/trace.go:171","msg":"trace[26731911] transaction","detail":"{read_only:false; response_revision:5540; number_of_response:1; }","duration":"103.458864ms","start":"2026-05-05T13:55:39.79844Z","end":"2026-05-05T13:55:39.901899Z","steps":["trace[26731911] 'process raft request' (duration: 50.119373ms)","trace[26731911] 'compare' (duration: 52.865778ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:56:31.982922Z","caller":"traceutil/trace.go:171","msg":"trace[2028858540] transaction","detail":"{read_only:false; response_revision:5969; number_of_response:1; }","duration":"126.20992ms","start":"2026-05-05T13:56:31.85669Z","end":"2026-05-05T13:56:31.9829Z","steps":["trace[2028858540] 'process raft request' (duration: 126.055547ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:56:43.617242Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2970} {"level":"info","ts":"2026-05-05T13:56:43.690271Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2970,"took":"71.266432ms","hash":2387086391,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":33796096,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-05T13:56:43.690333Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2387086391,"revision":2970,"compact-revision":-1} {"level":"info","ts":"2026-05-05T14:01:22.55313Z","caller":"traceutil/trace.go:171","msg":"trace[305281424] transaction","detail":"{read_only:false; response_revision:8137; number_of_response:1; }","duration":"100.040893ms","start":"2026-05-05T14:01:22.453053Z","end":"2026-05-05T14:01:22.553094Z","steps":["trace[305281424] 'process raft request' (duration: 99.740525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:24.359442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:01:24.359565Z","caller":"traceutil/trace.go:171","msg":"trace[219338978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8144; }","duration":"268.666225ms","start":"2026-05-05T14:01:24.090866Z","end":"2026-05-05T14:01:24.359532Z","steps":["trace[219338978] 'range keys from in-memory index tree' (duration: 268.361556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:24.359571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.697367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-05T14:01:24.359636Z","caller":"traceutil/trace.go:171","msg":"trace[1866510214] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8144; }","duration":"574.81276ms","start":"2026-05-05T14:01:23.784806Z","end":"2026-05-05T14:01:24.359619Z","steps":["trace[1866510214] 'range keys from in-memory index tree' (duration: 574.494261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:24.359672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:01:23.784784Z","time spent":"574.878502ms","remote":"127.0.0.1:51718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-05T14:01:24.359727Z","caller":"traceutil/trace.go:171","msg":"trace[690125950] transaction","detail":"{read_only:false; response_revision:8145; number_of_response:1; }","duration":"216.33085ms","start":"2026-05-05T14:01:24.143346Z","end":"2026-05-05T14:01:24.359676Z","steps":["trace[690125950] 'process raft request' (duration: 136.427775ms)","trace[690125950] 'compare' (duration: 79.746531ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:01:35.245483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.948442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:01:35.245582Z","caller":"traceutil/trace.go:171","msg":"trace[1880848683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8202; }","duration":"157.101477ms","start":"2026-05-05T14:01:35.088461Z","end":"2026-05-05T14:01:35.245563Z","steps":["trace[1880848683] 'range keys from in-memory index tree' (duration: 156.867851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:35.24563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.767579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-05T14:01:35.245729Z","caller":"traceutil/trace.go:171","msg":"trace[162594714] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8202; }","duration":"127.927863ms","start":"2026-05-05T14:01:35.11778Z","end":"2026-05-05T14:01:35.245708Z","steps":["trace[162594714] 'range keys from in-memory index tree' (duration: 127.588033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:35.245796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.408804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nms2w\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-05T14:01:35.245898Z","caller":"traceutil/trace.go:171","msg":"trace[832001688] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nms2w; range_end:; response_count:1; response_revision:8202; }","duration":"125.555898ms","start":"2026-05-05T14:01:35.120315Z","end":"2026-05-05T14:01:35.245871Z","steps":["trace[832001688] 'range keys from in-memory index tree' (duration: 125.25799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:35.246333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.438297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T14:01:35.246396Z","caller":"traceutil/trace.go:171","msg":"trace[546226984] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8202; }","duration":"218.558851ms","start":"2026-05-05T14:01:35.02782Z","end":"2026-05-05T14:01:35.246379Z","steps":["trace[546226984] 'range keys from in-memory index tree' (duration: 217.582795ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:36.568992Z","caller":"traceutil/trace.go:171","msg":"trace[2064950623] transaction","detail":"{read_only:false; response_revision:8211; number_of_response:1; }","duration":"168.741965ms","start":"2026-05-05T14:01:36.400232Z","end":"2026-05-05T14:01:36.568974Z","steps":["trace[2064950623] 'process raft request' (duration: 168.388895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:37.320164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.412724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:01:37.320282Z","caller":"traceutil/trace.go:171","msg":"trace[1430469068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8215; }","duration":"230.558608ms","start":"2026-05-05T14:01:37.089701Z","end":"2026-05-05T14:01:37.32026Z","steps":["trace[1430469068] 'range keys from in-memory index tree' (duration: 230.355601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:38.084034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.127905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T14:01:38.084183Z","caller":"traceutil/trace.go:171","msg":"trace[477828943] transaction","detail":"{read_only:false; response_revision:8217; number_of_response:1; }","duration":"759.763146ms","start":"2026-05-05T14:01:37.324406Z","end":"2026-05-05T14:01:38.084168Z","steps":["trace[477828943] 'process raft request' (duration: 547.435316ms)","trace[477828943] 'compare' (duration: 211.883358ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:01:38.084231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:01:37.324387Z","time spent":"759.827138ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T14:01:38.084386Z","caller":"traceutil/trace.go:171","msg":"trace[855638020] transaction","detail":"{read_only:false; response_revision:8218; number_of_response:1; }","duration":"759.95998ms","start":"2026-05-05T14:01:37.324419Z","end":"2026-05-05T14:01:38.084379Z","steps":["trace[855638020] 'process raft request' (duration: 759.691363ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:38.084397Z","caller":"traceutil/trace.go:171","msg":"trace[1754973314] linearizableReadLoop","detail":"{readStateIndex:8510; appliedIndex:8507; }","duration":"759.93574ms","start":"2026-05-05T14:01:37.324437Z","end":"2026-05-05T14:01:38.084373Z","steps":["trace[1754973314] 'read index received' (duration: 26.83601ms)","trace[1754973314] 'applied index is now lower than readState.Index' (duration: 733.09745ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:01:38.084425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:01:37.324401Z","time spent":"760.004373ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T14:01:38.084647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.452869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T14:01:38.084676Z","caller":"traceutil/trace.go:171","msg":"trace[2095026309] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8218; }","duration":"430.520862ms","start":"2026-05-05T14:01:37.654148Z","end":"2026-05-05T14:01:38.084669Z","steps":["trace[2095026309] 'agreement among raft nodes before linearized reading' (duration: 430.351027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:38.084698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:01:37.654132Z","time spent":"430.560593ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T14:01:38.084724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.617947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T14:01:38.084758Z","caller":"traceutil/trace.go:171","msg":"trace[571654984] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8218; }","duration":"436.755712ms","start":"2026-05-05T14:01:37.647996Z","end":"2026-05-05T14:01:38.084751Z","steps":["trace[571654984] 'agreement among raft nodes before linearized reading' (duration: 436.496434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:38.084785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:01:37.647964Z","time spent":"436.806113ms","remote":"127.0.0.1:51796","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-05-05T14:01:38.084833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.378242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223829"} {"level":"info","ts":"2026-05-05T14:01:38.084896Z","caller":"traceutil/trace.go:171","msg":"trace[1681188860] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8218; }","duration":"760.471606ms","start":"2026-05-05T14:01:37.32441Z","end":"2026-05-05T14:01:38.084881Z","steps":["trace[1681188860] 'agreement among raft nodes before linearized reading' (duration: 760.062145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:38.084931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:01:37.324398Z","time spent":"760.526677ms","remote":"127.0.0.1:51718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":25,"response size":223852,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-05-05T14:01:38.253637Z","caller":"traceutil/trace.go:171","msg":"trace[212115754] transaction","detail":"{read_only:false; response_revision:8219; number_of_response:1; }","duration":"165.195217ms","start":"2026-05-05T14:01:38.088414Z","end":"2026-05-05T14:01:38.253609Z","steps":["trace[212115754] 'process raft request' (duration: 110.710114ms)","trace[212115754] 'compare' (duration: 54.306178ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:01:38.25382Z","caller":"traceutil/trace.go:171","msg":"trace[780096479] transaction","detail":"{read_only:false; response_revision:8220; number_of_response:1; }","duration":"164.88203ms","start":"2026-05-05T14:01:38.088908Z","end":"2026-05-05T14:01:38.25379Z","steps":["trace[780096479] 'process raft request' (duration: 164.658444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:38.9941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.226782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T14:01:38.994286Z","caller":"traceutil/trace.go:171","msg":"trace[246033715] transaction","detail":"{read_only:false; response_revision:8223; number_of_response:1; }","duration":"336.588754ms","start":"2026-05-05T14:01:38.657679Z","end":"2026-05-05T14:01:38.994268Z","steps":["trace[246033715] 'process raft request' (duration: 336.532692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:38.994368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:01:38.657659Z","time spent":"336.673497ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T14:01:38.994467Z","caller":"traceutil/trace.go:171","msg":"trace[2028538765] transaction","detail":"{read_only:false; response_revision:8222; number_of_response:1; }","duration":"420.175681ms","start":"2026-05-05T14:01:38.574262Z","end":"2026-05-05T14:01:38.994438Z","steps":["trace[2028538765] 'process raft request' (duration: 230.507426ms)","trace[2028538765] 'compare' (duration: 189.092139ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:01:38.994633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:01:38.574237Z","time spent":"420.316444ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T14:01:38.994668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.989011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-868685dcb-gxvx5.18acb03149e7bc9f\" ","response":"range_response_count:1 size:1132"} {"level":"info","ts":"2026-05-05T14:01:38.994484Z","caller":"traceutil/trace.go:171","msg":"trace[1931078476] linearizableReadLoop","detail":"{readStateIndex:8514; appliedIndex:8513; }","duration":"348.788906ms","start":"2026-05-05T14:01:38.645674Z","end":"2026-05-05T14:01:38.994462Z","steps":["trace[1931078476] 'read index received' (duration: 158.591398ms)","trace[1931078476] 'applied index is now lower than readState.Index' (duration: 190.195228ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:01:38.994722Z","caller":"traceutil/trace.go:171","msg":"trace[911179900] range","detail":"{range_begin:/registry/events/openstack/barbican-api-868685dcb-gxvx5.18acb03149e7bc9f; range_end:; response_count:1; response_revision:8223; }","duration":"349.074584ms","start":"2026-05-05T14:01:38.645633Z","end":"2026-05-05T14:01:38.994707Z","steps":["trace[911179900] 'agreement among raft nodes before linearized reading' (duration: 348.889558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:38.994767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:01:38.645617Z","time spent":"349.139285ms","remote":"127.0.0.1:51624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1155,"request content":"key:\"/registry/events/openstack/barbican-api-868685dcb-gxvx5.18acb03149e7bc9f\" "} {"level":"info","ts":"2026-05-05T14:01:39.132759Z","caller":"traceutil/trace.go:171","msg":"trace[251736524] transaction","detail":"{read_only:false; response_revision:8224; number_of_response:1; }","duration":"136.044885ms","start":"2026-05-05T14:01:38.996683Z","end":"2026-05-05T14:01:39.132728Z","steps":["trace[251736524] 'process raft request' (duration: 123.925815ms)","trace[251736524] 'compare' (duration: 11.998326ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:01:39.172872Z","caller":"traceutil/trace.go:171","msg":"trace[1483654623] linearizableReadLoop","detail":"{readStateIndex:8517; appliedIndex:8515; }","duration":"149.984094ms","start":"2026-05-05T14:01:39.022867Z","end":"2026-05-05T14:01:39.172851Z","steps":["trace[1483654623] 'read index received' (duration: 97.741802ms)","trace[1483654623] 'applied index is now lower than readState.Index' (duration: 52.241352ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:01:39.172993Z","caller":"traceutil/trace.go:171","msg":"trace[888914123] transaction","detail":"{read_only:false; response_revision:8225; number_of_response:1; }","duration":"169.273308ms","start":"2026-05-05T14:01:39.00371Z","end":"2026-05-05T14:01:39.172983Z","steps":["trace[888914123] 'process raft request' (duration: 169.025092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:39.173371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.493078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T14:01:39.173427Z","caller":"traceutil/trace.go:171","msg":"trace[1627859577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8225; }","duration":"150.60147ms","start":"2026-05-05T14:01:39.022813Z","end":"2026-05-05T14:01:39.173414Z","steps":["trace[1627859577] 'agreement among raft nodes before linearized reading' (duration: 150.408855ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:39.494271Z","caller":"traceutil/trace.go:171","msg":"trace[1648761718] transaction","detail":"{read_only:false; response_revision:8227; number_of_response:1; }","duration":"135.626572ms","start":"2026-05-05T14:01:39.358621Z","end":"2026-05-05T14:01:39.494247Z","steps":["trace[1648761718] 'process raft request' (duration: 135.399536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:39.805835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.208521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-05T14:01:39.805917Z","caller":"traceutil/trace.go:171","msg":"trace[1262544045] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8227; }","duration":"178.340626ms","start":"2026-05-05T14:01:39.62756Z","end":"2026-05-05T14:01:39.805901Z","steps":["trace[1262544045] 'range keys from in-memory index tree' (duration: 177.996805ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:43.62401Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6062} {"level":"info","ts":"2026-05-05T14:01:43.72596Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6062,"took":"99.656414ms","hash":3242065897,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":23064576,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-05T14:01:43.726294Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3242065897,"revision":6062,"compact-revision":2970} {"level":"warn","ts":"2026-05-05T14:02:03.565425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.99335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/rook-ceph/csi-cephfsplugin-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:02:03.565508Z","caller":"traceutil/trace.go:171","msg":"trace[1170583043] range","detail":"{range_begin:/registry/services/specs/rook-ceph/csi-cephfsplugin-metrics; range_end:; response_count:0; response_revision:8404; }","duration":"101.088843ms","start":"2026-05-05T14:02:03.464399Z","end":"2026-05-05T14:02:03.565488Z","steps":["trace[1170583043] 'range keys from in-memory index tree' (duration: 100.920808ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:02:05.486806Z","caller":"traceutil/trace.go:171","msg":"trace[1562186667] linearizableReadLoop","detail":"{readStateIndex:8717; appliedIndex:8716; }","duration":"187.31601ms","start":"2026-05-05T14:02:05.299438Z","end":"2026-05-05T14:02:05.486754Z","steps":["trace[1562186667] 'read index received' (duration: 127.142421ms)","trace[1562186667] 'applied index is now lower than readState.Index' (duration: 60.172709ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:02:05.486924Z","caller":"traceutil/trace.go:171","msg":"trace[128989826] transaction","detail":"{read_only:false; response_revision:8417; number_of_response:1; }","duration":"213.704298ms","start":"2026-05-05T14:02:05.273211Z","end":"2026-05-05T14:02:05.486915Z","steps":["trace[128989826] 'process raft request' (duration: 153.352185ms)","trace[128989826] 'compare' (duration: 60.053915ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:02:05.487146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.700811ms","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-05-05T14:02:05.487168Z","caller":"traceutil/trace.go:171","msg":"trace[74875352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8417; }","duration":"187.775153ms","start":"2026-05-05T14:02:05.299387Z","end":"2026-05-05T14:02:05.487162Z","steps":["trace[74875352] 'agreement among raft nodes before linearized reading' (duration: 187.65241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:05.487172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.598722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-05T14:02:05.487258Z","caller":"traceutil/trace.go:171","msg":"trace[262433544] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8417; }","duration":"171.720275ms","start":"2026-05-05T14:02:05.315516Z","end":"2026-05-05T14:02:05.487237Z","steps":["trace[262433544] 'agreement among raft nodes before linearized reading' (duration: 171.431928ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:02:07.543282Z","caller":"traceutil/trace.go:171","msg":"trace[976873827] linearizableReadLoop","detail":"{readStateIndex:8728; appliedIndex:8727; }","duration":"452.154721ms","start":"2026-05-05T14:02:07.091106Z","end":"2026-05-05T14:02:07.543261Z","steps":["trace[976873827] 'read index received' (duration: 452.027797ms)","trace[976873827] 'applied index is now lower than readState.Index' (duration: 125.874µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:02:07.543377Z","caller":"traceutil/trace.go:171","msg":"trace[833650957] transaction","detail":"{read_only:false; response_revision:8427; number_of_response:1; }","duration":"452.87985ms","start":"2026-05-05T14:02:07.090461Z","end":"2026-05-05T14:02:07.54334Z","steps":["trace[833650957] 'process raft request' (duration: 452.626643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:07.54347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.322225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:02:07.54355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:07.090443Z","time spent":"453.015704ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T14:02:07.543553Z","caller":"traceutil/trace.go:171","msg":"trace[691444396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8427; }","duration":"452.449028ms","start":"2026-05-05T14:02:07.091083Z","end":"2026-05-05T14:02:07.543532Z","steps":["trace[691444396] 'agreement among raft nodes before linearized reading' (duration: 452.300255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:07.543624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:07.091076Z","time spent":"452.532411ms","remote":"127.0.0.1:51546","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-05-05T14:02:07.544571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.373353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:02:07.544629Z","caller":"traceutil/trace.go:171","msg":"trace[1599559996] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:8429; }","duration":"154.477706ms","start":"2026-05-05T14:02:07.390138Z","end":"2026-05-05T14:02:07.544616Z","steps":["trace[1599559996] 'agreement among raft nodes before linearized reading' (duration: 154.361123ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:02:07.54472Z","caller":"traceutil/trace.go:171","msg":"trace[2115111640] transaction","detail":"{read_only:false; response_revision:8429; number_of_response:1; }","duration":"261.85454ms","start":"2026-05-05T14:02:07.282843Z","end":"2026-05-05T14:02:07.544697Z","steps":["trace[2115111640] 'process raft request' (duration: 261.606104ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:02:07.544905Z","caller":"traceutil/trace.go:171","msg":"trace[675650466] transaction","detail":"{read_only:false; response_revision:8428; number_of_response:1; }","duration":"443.778773ms","start":"2026-05-05T14:02:07.101116Z","end":"2026-05-05T14:02:07.544895Z","steps":["trace[675650466] 'process raft request' (duration: 443.231028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:07.545011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:07.101104Z","time spent":"443.832194ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T14:02:08.106391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.214758ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T14:02:08.106486Z","caller":"traceutil/trace.go:171","msg":"trace[1619997389] linearizableReadLoop","detail":"{readStateIndex:8731; appliedIndex:8730; }","duration":"483.811692ms","start":"2026-05-05T14:02:07.622662Z","end":"2026-05-05T14:02:08.106474Z","steps":["trace[1619997389] 'read index received' (duration: 44.471µs)","trace[1619997389] 'applied index is now lower than readState.Index' (duration: 483.764051ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:02:08.106553Z","caller":"traceutil/trace.go:171","msg":"trace[33896545] transaction","detail":"{read_only:false; response_revision:8430; number_of_response:1; }","duration":"556.698316ms","start":"2026-05-05T14:02:07.549845Z","end":"2026-05-05T14:02:08.106543Z","steps":["trace[33896545] 'process raft request' (duration: 70.264682ms)","trace[33896545] 'compare' (duration: 486.032513ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:02:08.106594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:07.549823Z","time spent":"556.750748ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T14:02:08.106869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.207123ms","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-05-05T14:02:08.106895Z","caller":"traceutil/trace.go:171","msg":"trace[2014689115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8430; }","duration":"484.272986ms","start":"2026-05-05T14:02:07.622615Z","end":"2026-05-05T14:02:08.106888Z","steps":["trace[2014689115] 'agreement among raft nodes before linearized reading' (duration: 484.181463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:08.106914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:07.622597Z","time spent":"484.313226ms","remote":"127.0.0.1:51796","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-05-05T14:02:08.106884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.164882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T14:02:08.106979Z","caller":"traceutil/trace.go:171","msg":"trace[755462273] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:8430; }","duration":"358.329206ms","start":"2026-05-05T14:02:07.748627Z","end":"2026-05-05T14:02:08.106957Z","steps":["trace[755462273] 'agreement among raft nodes before linearized reading' (duration: 358.11719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:08.107045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:07.748608Z","time spent":"358.421098ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-05-05T14:02:08.505811Z","caller":"traceutil/trace.go:171","msg":"trace[1725717008] transaction","detail":"{read_only:false; response_revision:8432; number_of_response:1; }","duration":"204.303732ms","start":"2026-05-05T14:02:08.301479Z","end":"2026-05-05T14:02:08.505783Z","steps":["trace[1725717008] 'process raft request' (duration: 204.158559ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:02:08.5167Z","caller":"traceutil/trace.go:171","msg":"trace[2131270380] linearizableReadLoop","detail":"{readStateIndex:8734; appliedIndex:8733; }","duration":"128.220581ms","start":"2026-05-05T14:02:08.388452Z","end":"2026-05-05T14:02:08.516673Z","steps":["trace[2131270380] 'read index received' (duration: 117.54922ms)","trace[2131270380] 'applied index is now lower than readState.Index' (duration: 10.670011ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:02:08.51678Z","caller":"traceutil/trace.go:171","msg":"trace[131301934] transaction","detail":"{read_only:false; response_revision:8433; number_of_response:1; }","duration":"214.572053ms","start":"2026-05-05T14:02:08.302173Z","end":"2026-05-05T14:02:08.516745Z","steps":["trace[131301934] 'process raft request' (duration: 214.354647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:08.51697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.792269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T14:02:08.517016Z","caller":"traceutil/trace.go:171","msg":"trace[1241918620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8433; }","duration":"127.887022ms","start":"2026-05-05T14:02:08.389116Z","end":"2026-05-05T14:02:08.517003Z","steps":["trace[1241918620] 'agreement among raft nodes before linearized reading' (duration: 127.710427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:08.517092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.609692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T14:02:08.517205Z","caller":"traceutil/trace.go:171","msg":"trace[2123005413] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8433; }","duration":"128.803897ms","start":"2026-05-05T14:02:08.388381Z","end":"2026-05-05T14:02:08.517184Z","steps":["trace[2123005413] 'agreement among raft nodes before linearized reading' (duration: 128.445617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:20.730941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.331229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T14:02:20.731146Z","caller":"traceutil/trace.go:171","msg":"trace[1354970369] transaction","detail":"{read_only:false; response_revision:8536; number_of_response:1; }","duration":"353.362431ms","start":"2026-05-05T14:02:20.377764Z","end":"2026-05-05T14:02:20.731126Z","steps":["trace[1354970369] 'process raft request' (duration: 186.758605ms)","trace[1354970369] 'compare' (duration: 166.147004ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:02:20.731219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:20.377735Z","time spent":"353.453123ms","remote":"127.0.0.1:51718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4633,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T14:02:20.731332Z","caller":"traceutil/trace.go:171","msg":"trace[593607888] linearizableReadLoop","detail":"{readStateIndex:8843; appliedIndex:8841; }","duration":"174.50027ms","start":"2026-05-05T14:02:20.556804Z","end":"2026-05-05T14:02:20.731304Z","steps":["trace[593607888] 'read index received' (duration: 7.685859ms)","trace[593607888] 'applied index is now lower than readState.Index' (duration: 166.813391ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:02:20.731502Z","caller":"traceutil/trace.go:171","msg":"trace[1109235851] transaction","detail":"{read_only:false; response_revision:8537; number_of_response:1; }","duration":"233.006214ms","start":"2026-05-05T14:02:20.49847Z","end":"2026-05-05T14:02:20.731476Z","steps":["trace[1109235851] 'process raft request' (duration: 232.573103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:20.731619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.81242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-05T14:02:20.731676Z","caller":"traceutil/trace.go:171","msg":"trace[227529431] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8537; }","duration":"174.915982ms","start":"2026-05-05T14:02:20.556747Z","end":"2026-05-05T14:02:20.731663Z","steps":["trace[227529431] 'agreement among raft nodes before linearized reading' (duration: 174.730817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:21.075071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.2025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T14:02:21.075185Z","caller":"traceutil/trace.go:171","msg":"trace[212069527] linearizableReadLoop","detail":"{readStateIndex:8844; appliedIndex:8843; }","duration":"343.74895ms","start":"2026-05-05T14:02:20.731408Z","end":"2026-05-05T14:02:21.075157Z","steps":["trace[212069527] 'read index received' (duration: 204.417686ms)","trace[212069527] 'applied index is now lower than readState.Index' (duration: 139.330104ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:02:21.07537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.90424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-05T14:02:21.0754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.667113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T14:02:21.075425Z","caller":"traceutil/trace.go:171","msg":"trace[300846250] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"398.50333ms","start":"2026-05-05T14:02:20.676878Z","end":"2026-05-05T14:02:21.075381Z","steps":["trace[300846250] 'process raft request' (duration: 258.920099ms)","trace[300846250] 'compare' (duration: 138.744198ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:02:21.075494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.755447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-2r4cw\" ","response":"range_response_count:1 size:4648"} {"level":"warn","ts":"2026-05-05T14:02:21.075491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.212968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T14:02:21.075538Z","caller":"traceutil/trace.go:171","msg":"trace[1565730062] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-2r4cw; range_end:; response_count:1; response_revision:8538; }","duration":"398.835518ms","start":"2026-05-05T14:02:20.676692Z","end":"2026-05-05T14:02:21.075528Z","steps":["trace[1565730062] 'agreement among raft nodes before linearized reading' (duration: 398.728505ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:02:21.075552Z","caller":"traceutil/trace.go:171","msg":"trace[330943724] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8538; }","duration":"504.324621ms","start":"2026-05-05T14:02:20.571211Z","end":"2026-05-05T14:02:21.075536Z","steps":["trace[330943724] 'agreement among raft nodes before linearized reading' (duration: 504.194127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:21.075551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:20.676865Z","time spent":"398.625553ms","remote":"127.0.0.1:51624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T14:02:21.07558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:20.676673Z","time spent":"398.896671ms","remote":"127.0.0.1:51718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4671,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-2r4cw\" "} {"level":"warn","ts":"2026-05-05T14:02:21.075585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:20.571177Z","time spent":"504.400193ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T14:02:21.075492Z","caller":"traceutil/trace.go:171","msg":"trace[626423122] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8538; }","duration":"445.807798ms","start":"2026-05-05T14:02:20.629665Z","end":"2026-05-05T14:02:21.075472Z","steps":["trace[626423122] 'agreement among raft nodes before linearized reading' (duration: 445.53434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:21.075836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:20.629647Z","time spent":"446.122266ms","remote":"127.0.0.1:51796","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-05-05T14:02:21.076594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.361937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-05T14:02:21.07681Z","caller":"traceutil/trace.go:171","msg":"trace[866285188] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8538; }","duration":"467.979141ms","start":"2026-05-05T14:02:20.607415Z","end":"2026-05-05T14:02:21.075395Z","steps":["trace[866285188] 'agreement among raft nodes before linearized reading' (duration: 467.826537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:21.07687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:20.607396Z","time spent":"469.458022ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T14:02:21.076969Z","caller":"traceutil/trace.go:171","msg":"trace[1171698674] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8538; }","duration":"478.488658ms","start":"2026-05-05T14:02:20.598351Z","end":"2026-05-05T14:02:21.07684Z","steps":["trace[1171698674] 'agreement among raft nodes before linearized reading' (duration: 476.990877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:21.077032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:20.598324Z","time spent":"478.698003ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-05-05T14:02:21.128912Z","caller":"traceutil/trace.go:171","msg":"trace[966770981] transaction","detail":"{read_only:false; response_revision:8539; number_of_response:1; }","duration":"391.175921ms","start":"2026-05-05T14:02:20.73772Z","end":"2026-05-05T14:02:21.128896Z","steps":["trace[966770981] 'process raft request' (duration: 390.951215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:21.129003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:20.737696Z","time spent":"391.256994ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T14:02:21.129203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.508731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-05T14:02:21.129375Z","caller":"traceutil/trace.go:171","msg":"trace[85093739] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8539; }","duration":"351.720397ms","start":"2026-05-05T14:02:20.777628Z","end":"2026-05-05T14:02:21.129348Z","steps":["trace[85093739] 'agreement among raft nodes before linearized reading' (duration: 351.310536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:21.129488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:20.777614Z","time spent":"351.85777ms","remote":"127.0.0.1:51718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-05T14:02:21.130555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.697461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-2r4cw\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-05-05T14:02:21.130671Z","caller":"traceutil/trace.go:171","msg":"trace[112887919] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-2r4cw; range_end:; response_count:1; response_revision:8539; }","duration":"392.847386ms","start":"2026-05-05T14:02:20.737804Z","end":"2026-05-05T14:02:21.130651Z","steps":["trace[112887919] 'agreement among raft nodes before linearized reading' (duration: 391.028297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:21.130834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:20.737783Z","time spent":"393.037932ms","remote":"127.0.0.1:51718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4671,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-2r4cw\" "} {"level":"info","ts":"2026-05-05T14:02:21.741477Z","caller":"traceutil/trace.go:171","msg":"trace[1913339686] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"151.867465ms","start":"2026-05-05T14:02:21.589585Z","end":"2026-05-05T14:02:21.741452Z","steps":["trace[1913339686] 'process raft request' (duration: 151.741111ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:02:22.182123Z","caller":"traceutil/trace.go:171","msg":"trace[1267743975] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"583.044444ms","start":"2026-05-05T14:02:21.599046Z","end":"2026-05-05T14:02:22.18209Z","steps":["trace[1267743975] 'process raft request' (duration: 582.781567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:22.182321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:21.59902Z","time spent":"583.19836ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T14:02:22.182598Z","caller":"traceutil/trace.go:171","msg":"trace[933684594] transaction","detail":"{read_only:false; response_revision:8548; number_of_response:1; }","duration":"582.838099ms","start":"2026-05-05T14:02:21.599713Z","end":"2026-05-05T14:02:22.182551Z","steps":["trace[933684594] 'process raft request' (duration: 582.315694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:22.182876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:21.599697Z","time spent":"583.092275ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T14:02:22.496654Z","caller":"traceutil/trace.go:171","msg":"trace[2033409255] linearizableReadLoop","detail":"{readStateIndex:8855; appliedIndex:8852; }","duration":"653.673948ms","start":"2026-05-05T14:02:21.842953Z","end":"2026-05-05T14:02:22.496627Z","steps":["trace[2033409255] 'read index received' (duration: 338.924148ms)","trace[2033409255] 'applied index is now lower than readState.Index' (duration: 314.74835ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:02:22.496854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.891133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-05-05T14:02:22.49693Z","caller":"traceutil/trace.go:171","msg":"trace[1368954291] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8548; }","duration":"654.008187ms","start":"2026-05-05T14:02:21.842904Z","end":"2026-05-05T14:02:22.496912Z","steps":["trace[1368954291] 'agreement among raft nodes before linearized reading' (duration: 653.802101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:22.496975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:21.842885Z","time spent":"654.082188ms","remote":"127.0.0.1:48622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5755,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"warn","ts":"2026-05-05T14:02:22.497017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.297123ms","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-05-05T14:02:22.497059Z","caller":"traceutil/trace.go:171","msg":"trace[620913366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8548; }","duration":"330.373575ms","start":"2026-05-05T14:02:22.166675Z","end":"2026-05-05T14:02:22.497049Z","steps":["trace[620913366] 'agreement among raft nodes before linearized reading' (duration: 330.282683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:22.497098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:22.166654Z","time spent":"330.436866ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T14:02:22.496933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.082242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:3173"} {"level":"info","ts":"2026-05-05T14:02:22.497228Z","caller":"traceutil/trace.go:171","msg":"trace[1526940001] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:8548; }","duration":"581.42001ms","start":"2026-05-05T14:02:21.915795Z","end":"2026-05-05T14:02:22.497215Z","steps":["trace[1526940001] 'agreement among raft nodes before linearized reading' (duration: 580.995639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:22.497265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:21.915779Z","time spent":"581.475271ms","remote":"127.0.0.1:48698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":3196,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" "} {"level":"warn","ts":"2026-05-05T14:02:22.497327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.630803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-05T14:02:22.497521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.696707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nms2w\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-05-05T14:02:22.497544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.324034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:02:22.497564Z","caller":"traceutil/trace.go:171","msg":"trace[1534106333] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nms2w; range_end:; response_count:1; response_revision:8548; }","duration":"116.7785ms","start":"2026-05-05T14:02:22.380775Z","end":"2026-05-05T14:02:22.497554Z","steps":["trace[1534106333] 'agreement among raft nodes before linearized reading' (duration: 116.666437ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:02:22.497595Z","caller":"traceutil/trace.go:171","msg":"trace[1335003051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8548; }","duration":"409.409848ms","start":"2026-05-05T14:02:22.08817Z","end":"2026-05-05T14:02:22.49758Z","steps":["trace[1335003051] 'agreement among raft nodes before linearized reading' (duration: 409.329605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:22.497632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:22.088153Z","time spent":"409.472749ms","remote":"127.0.0.1:51546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T14:02:22.497582Z","caller":"traceutil/trace.go:171","msg":"trace[870788502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8548; }","duration":"326.847829ms","start":"2026-05-05T14:02:22.170567Z","end":"2026-05-05T14:02:22.497414Z","steps":["trace[870788502] 'agreement among raft nodes before linearized reading' (duration: 326.331796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:22.497777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:22.170547Z","time spent":"327.213819ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T14:02:22.49792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.508959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:02:22.4981Z","caller":"traceutil/trace.go:171","msg":"trace[2086444501] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:8548; }","duration":"438.90696ms","start":"2026-05-05T14:02:22.059168Z","end":"2026-05-05T14:02:22.498075Z","steps":["trace[2086444501] 'agreement among raft nodes before linearized reading' (duration: 438.300664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:22.498248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:02:22.059145Z","time spent":"439.084325ms","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true "} {"level":"warn","ts":"2026-05-05T14:02:23.258335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.231017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-05T14:02:23.258404Z","caller":"traceutil/trace.go:171","msg":"trace[302263766] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8558; }","duration":"126.35635ms","start":"2026-05-05T14:02:23.132033Z","end":"2026-05-05T14:02:23.258389Z","steps":["trace[302263766] 'range keys from in-memory index tree' (duration: 126.107153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:02:23.258566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.826634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:02:23.258658Z","caller":"traceutil/trace.go:171","msg":"trace[1523572354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8558; }","duration":"169.948367ms","start":"2026-05-05T14:02:23.088687Z","end":"2026-05-05T14:02:23.258636Z","steps":["trace[1523572354] 'range keys from in-memory index tree' (duration: 169.770033ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:03:11.827561Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024a5.wal"} {"level":"info","ts":"2026-05-05T14:03:39.853485Z","caller":"traceutil/trace.go:171","msg":"trace[1181986336] linearizableReadLoop","detail":"{readStateIndex:9567; appliedIndex:9566; }","duration":"117.403839ms","start":"2026-05-05T14:03:39.736055Z","end":"2026-05-05T14:03:39.853459Z","steps":["trace[1181986336] 'read index received' (duration: 117.139811ms)","trace[1181986336] 'applied index is now lower than readState.Index' (duration: 263.068µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:03:39.853616Z","caller":"traceutil/trace.go:171","msg":"trace[1646762096] transaction","detail":"{read_only:false; response_revision:9238; number_of_response:1; }","duration":"164.069011ms","start":"2026-05-05T14:03:39.68951Z","end":"2026-05-05T14:03:39.853579Z","steps":["trace[1646762096] 'process raft request' (duration: 163.721052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:03:39.853769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.694936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-05T14:03:39.853844Z","caller":"traceutil/trace.go:171","msg":"trace[1860878470] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9238; }","duration":"117.814871ms","start":"2026-05-05T14:03:39.736008Z","end":"2026-05-05T14:03:39.853823Z","steps":["trace[1860878470] 'agreement among raft nodes before linearized reading' (duration: 117.615395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:03:39.853776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.714085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T14:03:39.853898Z","caller":"traceutil/trace.go:171","msg":"trace[1176838654] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9238; }","duration":"111.870869ms","start":"2026-05-05T14:03:39.742015Z","end":"2026-05-05T14:03:39.853886Z","steps":["trace[1176838654] 'agreement among raft nodes before linearized reading' (duration: 111.660013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:03:39.853943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.966049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T14:03:39.85406Z","caller":"traceutil/trace.go:171","msg":"trace[1171122486] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9238; }","duration":"109.116343ms","start":"2026-05-05T14:03:39.744917Z","end":"2026-05-05T14:03:39.854033Z","steps":["trace[1171122486] 'agreement among raft nodes before linearized reading' (duration: 108.793555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:03:40.252293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.953344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:03:40.252404Z","caller":"traceutil/trace.go:171","msg":"trace[851933764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9241; }","duration":"165.119189ms","start":"2026-05-05T14:03:40.08726Z","end":"2026-05-05T14:03:40.252379Z","steps":["trace[851933764] 'range keys from in-memory index tree' (duration: 164.875742ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:03:40.252941Z","caller":"traceutil/trace.go:171","msg":"trace[1805069333] transaction","detail":"{read_only:false; response_revision:9242; number_of_response:1; }","duration":"122.59226ms","start":"2026-05-05T14:03:40.130313Z","end":"2026-05-05T14:03:40.252906Z","steps":["trace[1805069333] 'process raft request' (duration: 82.317983ms)","trace[1805069333] 'compare' (duration: 39.882936ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:04:16.091004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.831162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ceph-object-controller-detect-version-dxzvb\" ","response":"range_response_count:1 size:4559"} {"level":"info","ts":"2026-05-05T14:04:16.091089Z","caller":"traceutil/trace.go:171","msg":"trace[65129790] range","detail":"{range_begin:/registry/pods/openstack/ceph-object-controller-detect-version-dxzvb; range_end:; response_count:1; response_revision:9573; }","duration":"291.978976ms","start":"2026-05-05T14:04:15.799093Z","end":"2026-05-05T14:04:16.091072Z","steps":["trace[65129790] 'range keys from in-memory index tree' (duration: 291.714879ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:04:16.293708Z","caller":"traceutil/trace.go:171","msg":"trace[1854505102] transaction","detail":"{read_only:false; response_revision:9574; number_of_response:1; }","duration":"198.037717ms","start":"2026-05-05T14:04:16.095635Z","end":"2026-05-05T14:04:16.293672Z","steps":["trace[1854505102] 'process raft request' (duration: 197.843732ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:04:16.329179Z","caller":"traceutil/trace.go:171","msg":"trace[1852468896] linearizableReadLoop","detail":"{readStateIndex:9912; appliedIndex:9909; }","duration":"206.61086ms","start":"2026-05-05T14:04:16.122542Z","end":"2026-05-05T14:04:16.329153Z","steps":["trace[1852468896] 'read index received' (duration: 170.972949ms)","trace[1852468896] 'applied index is now lower than readState.Index' (duration: 35.637071ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:04:16.329341Z","caller":"traceutil/trace.go:171","msg":"trace[62299340] transaction","detail":"{read_only:false; response_revision:9576; number_of_response:1; }","duration":"231.094986ms","start":"2026-05-05T14:04:16.098237Z","end":"2026-05-05T14:04:16.329332Z","steps":["trace[62299340] 'process raft request' (duration: 230.86166ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:04:16.329605Z","caller":"traceutil/trace.go:171","msg":"trace[547377842] transaction","detail":"{read_only:false; response_revision:9575; number_of_response:1; }","duration":"233.674698ms","start":"2026-05-05T14:04:16.095906Z","end":"2026-05-05T14:04:16.329581Z","steps":["trace[547377842] 'process raft request' (duration: 233.050051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:04:16.329654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.472724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-05-05T14:04:16.32964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.052522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:04:16.32973Z","caller":"traceutil/trace.go:171","msg":"trace[2002002360] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9576; }","duration":"192.562797ms","start":"2026-05-05T14:04:16.137146Z","end":"2026-05-05T14:04:16.329709Z","steps":["trace[2002002360] 'agreement among raft nodes before linearized reading' (duration: 192.30852ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:04:16.329761Z","caller":"traceutil/trace.go:171","msg":"trace[1767713595] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:9576; }","duration":"207.270017ms","start":"2026-05-05T14:04:16.122469Z","end":"2026-05-05T14:04:16.329739Z","steps":["trace[1767713595] 'agreement among raft nodes before linearized reading' (duration: 206.98943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:04:16.32994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.958996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T14:04:16.329979Z","caller":"traceutil/trace.go:171","msg":"trace[100787872] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9576; }","duration":"190.039438ms","start":"2026-05-05T14:04:16.13993Z","end":"2026-05-05T14:04:16.329969Z","steps":["trace[100787872] 'agreement among raft nodes before linearized reading' (duration: 189.937555ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:04:16.439674Z","caller":"traceutil/trace.go:171","msg":"trace[239321946] transaction","detail":"{read_only:false; response_revision:9578; number_of_response:1; }","duration":"102.281227ms","start":"2026-05-05T14:04:16.337364Z","end":"2026-05-05T14:04:16.439646Z","steps":["trace[239321946] 'process raft request' (duration: 102.003309ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:04:16.685667Z","caller":"traceutil/trace.go:171","msg":"trace[31755786] transaction","detail":"{read_only:false; response_revision:9581; number_of_response:1; }","duration":"123.422952ms","start":"2026-05-05T14:04:16.562208Z","end":"2026-05-05T14:04:16.685631Z","steps":["trace[31755786] 'process raft request' (duration: 123.202126ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:04:24.719202Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1ee87564e61d159f","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T14:04:24.724047Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-05T14:04:24.724141Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-05T14:05:07.460217Z","caller":"traceutil/trace.go:171","msg":"trace[233749201] transaction","detail":"{read_only:false; response_revision:9965; number_of_response:1; }","duration":"119.150653ms","start":"2026-05-05T14:05:07.341032Z","end":"2026-05-05T14:05:07.460182Z","steps":["trace[233749201] 'process raft request' (duration: 118.895856ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:05:07.460448Z","caller":"traceutil/trace.go:171","msg":"trace[1753168999] linearizableReadLoop","detail":"{readStateIndex:10316; appliedIndex:10314; }","duration":"114.018532ms","start":"2026-05-05T14:05:07.346409Z","end":"2026-05-05T14:05:07.460428Z","steps":["trace[1753168999] 'read index received' (duration: 7.339599ms)","trace[1753168999] 'applied index is now lower than readState.Index' (duration: 106.677523ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:05:07.460674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.245459ms","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-05-05T14:05:07.460781Z","caller":"traceutil/trace.go:171","msg":"trace[625380979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9966; }","duration":"114.384273ms","start":"2026-05-05T14:05:07.346383Z","end":"2026-05-05T14:05:07.460767Z","steps":["trace[625380979] 'agreement among raft nodes before linearized reading' (duration: 114.147987ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:05:07.460999Z","caller":"traceutil/trace.go:171","msg":"trace[1172405800] transaction","detail":"{read_only:false; response_revision:9966; number_of_response:1; }","duration":"113.977992ms","start":"2026-05-05T14:05:07.347001Z","end":"2026-05-05T14:05:07.460979Z","steps":["trace[1172405800] 'process raft request' (duration: 113.090738ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:05:21.405077Z","caller":"traceutil/trace.go:171","msg":"trace[630871308] transaction","detail":"{read_only:false; response_revision:10082; number_of_response:1; }","duration":"105.519693ms","start":"2026-05-05T14:05:21.299532Z","end":"2026-05-05T14:05:21.405051Z","steps":["trace[630871308] 'process raft request' (duration: 105.349817ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:05:22.903826Z","caller":"traceutil/trace.go:171","msg":"trace[1747353060] transaction","detail":"{read_only:false; response_revision:10087; number_of_response:1; }","duration":"151.954506ms","start":"2026-05-05T14:05:22.751847Z","end":"2026-05-05T14:05:22.903802Z","steps":["trace[1747353060] 'process raft request' (duration: 151.797271ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:05:22.922184Z","caller":"traceutil/trace.go:171","msg":"trace[1201321392] transaction","detail":"{read_only:false; response_revision:10088; number_of_response:1; }","duration":"100.746702ms","start":"2026-05-05T14:05:22.821395Z","end":"2026-05-05T14:05:22.922142Z","steps":["trace[1201321392] 'process raft request' (duration: 100.485476ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:05:34.985749Z","caller":"traceutil/trace.go:171","msg":"trace[173063535] transaction","detail":"{read_only:false; response_revision:10170; number_of_response:1; }","duration":"110.528318ms","start":"2026-05-05T14:05:34.875195Z","end":"2026-05-05T14:05:34.985723Z","steps":["trace[173063535] 'process raft request' (duration: 32.180276ms)","trace[173063535] 'compare' (duration: 77.707414ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:05:41.208501Z","caller":"traceutil/trace.go:171","msg":"trace[954480092] transaction","detail":"{read_only:false; response_revision:10216; number_of_response:1; }","duration":"117.490458ms","start":"2026-05-05T14:05:41.090969Z","end":"2026-05-05T14:05:41.20846Z","steps":["trace[954480092] 'process raft request' (duration: 34.813527ms)","trace[954480092] 'compare' (duration: 82.517576ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:05:57.255633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.118267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T14:05:57.255835Z","caller":"traceutil/trace.go:171","msg":"trace[486426547] transaction","detail":"{read_only:false; response_revision:10378; number_of_response:1; }","duration":"143.934686ms","start":"2026-05-05T14:05:57.11188Z","end":"2026-05-05T14:05:57.255815Z","steps":["trace[486426547] 'process raft request' (duration: 143.870015ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:05:57.255854Z","caller":"traceutil/trace.go:171","msg":"trace[240069602] linearizableReadLoop","detail":"{readStateIndex:10742; appliedIndex:10741; }","duration":"247.713111ms","start":"2026-05-05T14:05:57.008102Z","end":"2026-05-05T14:05:57.255815Z","steps":["trace[240069602] 'read index received' (duration: 112.42066ms)","trace[240069602] 'applied index is now lower than readState.Index' (duration: 135.289541ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:05:57.255895Z","caller":"traceutil/trace.go:171","msg":"trace[90816184] transaction","detail":"{read_only:false; response_revision:10377; number_of_response:1; }","duration":"280.807163ms","start":"2026-05-05T14:05:56.975059Z","end":"2026-05-05T14:05:57.255867Z","steps":["trace[90816184] 'process raft request' (duration: 145.370237ms)","trace[90816184] 'compare' (duration: 134.987484ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:05:57.256159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.04599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T14:05:57.256226Z","caller":"traceutil/trace.go:171","msg":"trace[2045960642] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10378; }","duration":"248.196505ms","start":"2026-05-05T14:05:57.008013Z","end":"2026-05-05T14:05:57.256209Z","steps":["trace[2045960642] 'agreement among raft nodes before linearized reading' (duration: 247.952589ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:05:57.296305Z","caller":"traceutil/trace.go:171","msg":"trace[1831021859] transaction","detail":"{read_only:false; response_revision:10379; number_of_response:1; }","duration":"142.775216ms","start":"2026-05-05T14:05:57.153512Z","end":"2026-05-05T14:05:57.296287Z","steps":["trace[1831021859] 'process raft request' (duration: 142.618032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:05:57.296548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.514808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:05:57.296622Z","caller":"traceutil/trace.go:171","msg":"trace[163431232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10380; }","duration":"207.642781ms","start":"2026-05-05T14:05:57.088964Z","end":"2026-05-05T14:05:57.296606Z","steps":["trace[163431232] 'agreement among raft nodes before linearized reading' (duration: 207.503147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:05:57.296615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.776721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-05T14:05:57.296683Z","caller":"traceutil/trace.go:171","msg":"trace[1361129527] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:10380; }","duration":"108.865343ms","start":"2026-05-05T14:05:57.187796Z","end":"2026-05-05T14:05:57.296662Z","steps":["trace[1361129527] 'agreement among raft nodes before linearized reading' (duration: 108.663997ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:05:57.296544Z","caller":"traceutil/trace.go:171","msg":"trace[315561765] transaction","detail":"{read_only:false; response_revision:10380; number_of_response:1; }","duration":"118.098293ms","start":"2026-05-05T14:05:57.178412Z","end":"2026-05-05T14:05:57.296511Z","steps":["trace[315561765] 'process raft request' (duration: 117.829296ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:06:15.63509Z","caller":"traceutil/trace.go:171","msg":"trace[2094529663] transaction","detail":"{read_only:false; response_revision:10572; number_of_response:1; }","duration":"242.711326ms","start":"2026-05-05T14:06:15.39235Z","end":"2026-05-05T14:06:15.635062Z","steps":["trace[2094529663] 'process raft request' (duration: 242.421068ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:06:15.635139Z","caller":"traceutil/trace.go:171","msg":"trace[1299215023] transaction","detail":"{read_only:false; response_revision:10571; number_of_response:1; }","duration":"243.402744ms","start":"2026-05-05T14:06:15.391452Z","end":"2026-05-05T14:06:15.634854Z","steps":["trace[1299215023] 'process raft request' (duration: 226.845463ms)","trace[1299215023] 'compare' (duration: 16.227712ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:06:15.635257Z","caller":"traceutil/trace.go:171","msg":"trace[1200816260] linearizableReadLoop","detail":"{readStateIndex:10940; appliedIndex:10938; }","duration":"242.442068ms","start":"2026-05-05T14:06:15.392774Z","end":"2026-05-05T14:06:15.635216Z","steps":["trace[1200816260] 'read index received' (duration: 225.548908ms)","trace[1200816260] 'applied index is now lower than readState.Index' (duration: 16.89166ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:06:15.635532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.741437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T14:06:15.63562Z","caller":"traceutil/trace.go:171","msg":"trace[1663130756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10573; }","duration":"242.86949ms","start":"2026-05-05T14:06:15.392732Z","end":"2026-05-05T14:06:15.635601Z","steps":["trace[1663130756] 'agreement among raft nodes before linearized reading' (duration: 242.634133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:06:15.635734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.303783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T14:06:15.635824Z","caller":"traceutil/trace.go:171","msg":"trace[964132855] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10573; }","duration":"239.454997ms","start":"2026-05-05T14:06:15.396347Z","end":"2026-05-05T14:06:15.635802Z","steps":["trace[964132855] 'agreement among raft nodes before linearized reading' (duration: 239.154539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:06:15.635806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.407852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-05T14:06:15.63594Z","caller":"traceutil/trace.go:171","msg":"trace[1198662815] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:10573; }","duration":"208.597268ms","start":"2026-05-05T14:06:15.427294Z","end":"2026-05-05T14:06:15.635891Z","steps":["trace[1198662815] 'agreement among raft nodes before linearized reading' (duration: 208.29816ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:06:15.636009Z","caller":"traceutil/trace.go:171","msg":"trace[232705738] transaction","detail":"{read_only:false; response_revision:10573; number_of_response:1; }","duration":"229.65874ms","start":"2026-05-05T14:06:15.406296Z","end":"2026-05-05T14:06:15.635955Z","steps":["trace[232705738] 'process raft request' (duration: 228.736534ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:06:15.750868Z","caller":"traceutil/trace.go:171","msg":"trace[854961931] transaction","detail":"{read_only:false; response_revision:10575; number_of_response:1; }","duration":"109.953983ms","start":"2026-05-05T14:06:15.640889Z","end":"2026-05-05T14:06:15.750843Z","steps":["trace[854961931] 'process raft request' (duration: 108.059631ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:06:15.751125Z","caller":"traceutil/trace.go:171","msg":"trace[565006185] transaction","detail":"{read_only:false; response_revision:10576; number_of_response:1; }","duration":"110.182459ms","start":"2026-05-05T14:06:15.640925Z","end":"2026-05-05T14:06:15.751107Z","steps":["trace[565006185] 'process raft request' (duration: 109.86823ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:06:15.751296Z","caller":"traceutil/trace.go:171","msg":"trace[105335830] transaction","detail":"{read_only:false; response_revision:10577; number_of_response:1; }","duration":"109.888761ms","start":"2026-05-05T14:06:15.641335Z","end":"2026-05-05T14:06:15.751223Z","steps":["trace[105335830] 'process raft request' (duration: 109.694326ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:06:40.678733Z","caller":"traceutil/trace.go:171","msg":"trace[1813560314] transaction","detail":"{read_only:false; response_revision:10757; number_of_response:1; }","duration":"190.936757ms","start":"2026-05-05T14:06:40.487751Z","end":"2026-05-05T14:06:40.678688Z","steps":["trace[1813560314] 'process raft request' (duration: 121.771795ms)","trace[1813560314] 'compare' (duration: 68.985347ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:06:40.860739Z","caller":"traceutil/trace.go:171","msg":"trace[390581549] linearizableReadLoop","detail":"{readStateIndex:11132; appliedIndex:11131; }","duration":"156.141528ms","start":"2026-05-05T14:06:40.704571Z","end":"2026-05-05T14:06:40.860712Z","steps":["trace[390581549] 'read index received' (duration: 115.053211ms)","trace[390581549] 'applied index is now lower than readState.Index' (duration: 41.087357ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:06:40.860835Z","caller":"traceutil/trace.go:171","msg":"trace[1269618320] transaction","detail":"{read_only:false; response_revision:10758; number_of_response:1; }","duration":"158.911895ms","start":"2026-05-05T14:06:40.701887Z","end":"2026-05-05T14:06:40.860799Z","steps":["trace[1269618320] 'process raft request' (duration: 117.715954ms)","trace[1269618320] 'compare' (duration: 40.970475ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:06:40.861091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.487438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-cinder-server\" ","response":"range_response_count:1 size:9544"} {"level":"info","ts":"2026-05-05T14:06:40.861197Z","caller":"traceutil/trace.go:171","msg":"trace[1640857809] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-cinder-server; range_end:; response_count:1; response_revision:10758; }","duration":"156.638844ms","start":"2026-05-05T14:06:40.70453Z","end":"2026-05-05T14:06:40.861169Z","steps":["trace[1640857809] 'agreement among raft nodes before linearized reading' (duration: 156.298644ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:06:43.630701Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8254} {"level":"info","ts":"2026-05-05T14:06:43.711626Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8254,"took":"78.667651ms","hash":1140958562,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":25559040,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-05T14:06:43.71172Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1140958562,"revision":8254,"compact-revision":6062} {"level":"info","ts":"2026-05-05T14:06:59.696701Z","caller":"traceutil/trace.go:171","msg":"trace[151563789] transaction","detail":"{read_only:false; response_revision:11043; number_of_response:1; }","duration":"108.591351ms","start":"2026-05-05T14:06:59.588082Z","end":"2026-05-05T14:06:59.696673Z","steps":["trace[151563789] 'process raft request' (duration: 108.408665ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:07:02.384781Z","caller":"traceutil/trace.go:171","msg":"trace[1399511085] linearizableReadLoop","detail":"{readStateIndex:11446; appliedIndex:11444; }","duration":"151.928888ms","start":"2026-05-05T14:07:02.232827Z","end":"2026-05-05T14:07:02.384756Z","steps":["trace[1399511085] 'read index received' (duration: 19.079848ms)","trace[1399511085] 'applied index is now lower than readState.Index' (duration: 132.8482ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:07:02.384826Z","caller":"traceutil/trace.go:171","msg":"trace[332605914] transaction","detail":"{read_only:false; response_revision:11063; number_of_response:1; }","duration":"154.188149ms","start":"2026-05-05T14:07:02.230594Z","end":"2026-05-05T14:07:02.384782Z","steps":["trace[332605914] 'process raft request' (duration: 109.951197ms)","trace[332605914] 'compare' (duration: 44.089769ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:07:02.384935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.070011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:07:02.385011Z","caller":"traceutil/trace.go:171","msg":"trace[1498614699] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:11063; }","duration":"152.179624ms","start":"2026-05-05T14:07:02.232808Z","end":"2026-05-05T14:07:02.384987Z","steps":["trace[1498614699] 'agreement among raft nodes before linearized reading' (duration: 152.050281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:07:18.689381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.074583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T14:07:18.68961Z","caller":"traceutil/trace.go:171","msg":"trace[43509753] linearizableReadLoop","detail":"{readStateIndex:11538; appliedIndex:11537; }","duration":"232.217468ms","start":"2026-05-05T14:07:18.457368Z","end":"2026-05-05T14:07:18.689585Z","steps":["trace[43509753] 'read index received' (duration: 96.372µs)","trace[43509753] 'applied index is now lower than readState.Index' (duration: 232.119746ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:07:18.690036Z","caller":"traceutil/trace.go:171","msg":"trace[374796604] transaction","detail":"{read_only:false; response_revision:11152; number_of_response:1; }","duration":"305.988232ms","start":"2026-05-05T14:07:18.384005Z","end":"2026-05-05T14:07:18.689993Z","steps":["trace[374796604] 'process raft request' (duration: 19.103348ms)","trace[374796604] 'compare' (duration: 285.888947ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:07:18.690269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.879636ms","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-05-05T14:07:18.690399Z","caller":"traceutil/trace.go:171","msg":"trace[351298739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11152; }","duration":"233.098243ms","start":"2026-05-05T14:07:18.457269Z","end":"2026-05-05T14:07:18.690367Z","steps":["trace[351298739] 'agreement among raft nodes before linearized reading' (duration: 232.803675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:07:18.690557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:07:18.383964Z","time spent":"306.163587ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T14:07:18.690642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.404502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nms2w\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-05T14:07:18.690834Z","caller":"traceutil/trace.go:171","msg":"trace[668672934] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nms2w; range_end:; response_count:1; response_revision:11152; }","duration":"130.568916ms","start":"2026-05-05T14:07:18.560161Z","end":"2026-05-05T14:07:18.69073Z","steps":["trace[668672934] 'agreement among raft nodes before linearized reading' (duration: 130.206777ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:07:40.547865Z","caller":"traceutil/trace.go:171","msg":"trace[1070203693] transaction","detail":"{read_only:false; response_revision:11320; number_of_response:1; }","duration":"106.354368ms","start":"2026-05-05T14:07:40.441477Z","end":"2026-05-05T14:07:40.547831Z","steps":["trace[1070203693] 'process raft request' (duration: 106.081621ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:08:02.326611Z","caller":"traceutil/trace.go:171","msg":"trace[906911947] transaction","detail":"{read_only:false; response_revision:11463; number_of_response:1; }","duration":"123.028552ms","start":"2026-05-05T14:08:02.203561Z","end":"2026-05-05T14:08:02.32659Z","steps":["trace[906911947] 'process raft request' (duration: 84.075314ms)","trace[906911947] 'compare' (duration: 38.859676ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:08:02.328385Z","caller":"traceutil/trace.go:171","msg":"trace[1235061209] transaction","detail":"{read_only:false; response_revision:11464; number_of_response:1; }","duration":"120.234336ms","start":"2026-05-05T14:08:02.208131Z","end":"2026-05-05T14:08:02.328365Z","steps":["trace[1235061209] 'process raft request' (duration: 119.894747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:08:02.64733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.497765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T14:08:02.647477Z","caller":"traceutil/trace.go:171","msg":"trace[829424978] transaction","detail":"{read_only:false; response_revision:11466; number_of_response:1; }","duration":"233.424551ms","start":"2026-05-05T14:08:02.414028Z","end":"2026-05-05T14:08:02.647452Z","steps":["trace[829424978] 'process raft request' (duration: 63.70457ms)","trace[829424978] 'compare' (duration: 168.847838ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:08:03.043343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.696552ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T14:08:03.043447Z","caller":"traceutil/trace.go:171","msg":"trace[1299424834] linearizableReadLoop","detail":"{readStateIndex:11863; appliedIndex:11862; }","duration":"294.410169ms","start":"2026-05-05T14:08:02.74902Z","end":"2026-05-05T14:08:03.043431Z","steps":["trace[1299424834] 'read index received' (duration: 58.467329ms)","trace[1299424834] 'applied index is now lower than readState.Index' (duration: 235.94173ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:08:03.04354Z","caller":"traceutil/trace.go:171","msg":"trace[1935225171] transaction","detail":"{read_only:false; response_revision:11467; number_of_response:1; }","duration":"318.275757ms","start":"2026-05-05T14:08:02.725253Z","end":"2026-05-05T14:08:03.043528Z","steps":["trace[1935225171] 'process raft request' (duration: 82.296136ms)","trace[1935225171] 'compare' (duration: 235.538809ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:08:03.043597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:08:02.725226Z","time spent":"318.339738ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T14:08:03.043735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.392055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-05T14:08:03.043848Z","caller":"traceutil/trace.go:171","msg":"trace[312212313] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:11467; }","duration":"133.554509ms","start":"2026-05-05T14:08:02.91025Z","end":"2026-05-05T14:08:03.043804Z","steps":["trace[312212313] 'agreement among raft nodes before linearized reading' (duration: 133.300792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:08:03.044127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.106557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T14:08:03.044168Z","caller":"traceutil/trace.go:171","msg":"trace[109738135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11467; }","duration":"295.176259ms","start":"2026-05-05T14:08:02.748978Z","end":"2026-05-05T14:08:03.044154Z","steps":["trace[109738135] 'agreement among raft nodes before linearized reading' (duration: 295.082746ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:11:43.63846Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10773} {"level":"info","ts":"2026-05-05T14:11:43.766913Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10773,"took":"125.941476ms","hash":2989373164,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":27680768,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-05T14:11:43.766968Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2989373164,"revision":10773,"compact-revision":8254} {"level":"warn","ts":"2026-05-05T14:13:00.295422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.378543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:13:00.295508Z","caller":"traceutil/trace.go:171","msg":"trace[62219893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13495; }","duration":"207.496197ms","start":"2026-05-05T14:13:00.087996Z","end":"2026-05-05T14:13:00.295492Z","steps":["trace[62219893] 'range keys from in-memory index tree' (duration: 207.327462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:13:00.295904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.895543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T14:13:00.295945Z","caller":"traceutil/trace.go:171","msg":"trace[1344258613] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13495; }","duration":"181.940534ms","start":"2026-05-05T14:13:00.113994Z","end":"2026-05-05T14:13:00.295935Z","steps":["trace[1344258613] 'range keys from in-memory index tree' (duration: 181.76997ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:16:43.648548Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12967} {"level":"info","ts":"2026-05-05T14:16:43.726435Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12967,"took":"74.907687ms","hash":1596483936,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":23646208,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-05T14:16:43.726508Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1596483936,"revision":12967,"compact-revision":10773}