{"level":"warn","ts":"2026-06-26T03:38:02.075393Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-26T03:38:02.07551Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.177:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.177:2380","--initial-cluster=instance=https://199.204.45.177:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.177:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.177: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-06-26T03:38:02.075589Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-26T03:38:02.075606Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-06-26T03:38:02.075631Z","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-06-26T03:38:02.07644Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"]} {"level":"info","ts":"2026-06-26T03:38:02.076529Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.177: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-06-26T03:38:02.080042Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.221244ms"} {"level":"info","ts":"2026-06-26T03:38:02.087188Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7c53a18098324dc9","cluster-id":"4717e8aa221973c8"} {"level":"info","ts":"2026-06-26T03:38:02.087294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=()"} {"level":"info","ts":"2026-06-26T03:38:02.08746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 0"} {"level":"info","ts":"2026-06-26T03:38:02.087475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7c53a18098324dc9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-26T03:38:02.087489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 1"} {"level":"info","ts":"2026-06-26T03:38:02.08755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"warn","ts":"2026-06-26T03:38:02.094723Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-26T03:38:02.096639Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-26T03:38:02.097777Z","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-06-26T03:38:02.100485Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7c53a18098324dc9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-26T03:38:02.100655Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7c53a18098324dc9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-26T03:38:02.100762Z","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-06-26T03:38:02.100873Z","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-06-26T03:38:02.100888Z","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-06-26T03:38:02.10259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"info","ts":"2026-06-26T03:38:02.103648Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","added-peer-id":"7c53a18098324dc9","added-peer-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-06-26T03:38:02.10664Z","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-06-26T03:38:02.107113Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7c53a18098324dc9","initial-advertise-peer-urls":["https://199.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-26T03:38:02.107188Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-26T03:38:02.107321Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-06-26T03:38:02.107358Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-06-26T03:38:02.187848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 is starting a new election at term 1"} {"level":"info","ts":"2026-06-26T03:38:02.187937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-26T03:38:02.187978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgPreVoteResp from 7c53a18098324dc9 at term 1"} {"level":"info","ts":"2026-06-26T03:38:02.188003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became candidate at term 2"} {"level":"info","ts":"2026-06-26T03:38:02.188015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgVoteResp from 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-06-26T03:38:02.188034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became leader at term 2"} {"level":"info","ts":"2026-06-26T03:38:02.18805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7c53a18098324dc9 elected leader 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-06-26T03:38:02.188939Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T03:38:02.189737Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7c53a18098324dc9","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.177:2379]}","request-path":"/0/members/7c53a18098324dc9/attributes","cluster-id":"4717e8aa221973c8","publish-timeout":"7s"} {"level":"info","ts":"2026-06-26T03:38:02.19012Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-26T03:38:02.19032Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-26T03:38:02.191214Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T03:38:02.19125Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-26T03:38:02.191282Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-26T03:38:02.191361Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T03:38:02.191407Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T03:38:02.194733Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.177:2379"} {"level":"info","ts":"2026-06-26T03:38:02.19888Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-26T03:39:10.556225Z","caller":"traceutil/trace.go:171","msg":"trace[1276549468] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"248.895212ms","start":"2026-06-26T03:39:10.30729Z","end":"2026-06-26T03:39:10.556185Z","steps":["trace[1276549468] 'process raft request' (duration: 248.639036ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:39:10.832402Z","caller":"traceutil/trace.go:171","msg":"trace[277742710] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"522.301643ms","start":"2026-06-26T03:39:10.310064Z","end":"2026-06-26T03:39:10.832365Z","steps":["trace[277742710] 'process raft request' (duration: 521.962306ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:10.832968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:10.310022Z","time spent":"522.477947ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:39:10.833171Z","caller":"traceutil/trace.go:171","msg":"trace[949488047] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"495.025495ms","start":"2026-06-26T03:39:10.338135Z","end":"2026-06-26T03:39:10.833161Z","steps":["trace[949488047] 'process raft request' (duration: 494.32037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:10.833231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:10.338088Z","time spent":"495.109416ms","remote":"127.0.0.1:38112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:39:10.833885Z","caller":"traceutil/trace.go:171","msg":"trace[1792398862] linearizableReadLoop","detail":"{readStateIndex:724; appliedIndex:722; }","duration":"217.576157ms","start":"2026-06-26T03:39:10.615529Z","end":"2026-06-26T03:39:10.833105Z","steps":["trace[1792398862] 'read index received' (duration: 216.532415ms)","trace[1792398862] 'applied index is now lower than readState.Index' (duration: 1.042962ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:39:10.834157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.982286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:39:10.834371Z","caller":"traceutil/trace.go:171","msg":"trace[1122855937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:695; }","duration":"218.890285ms","start":"2026-06-26T03:39:10.615451Z","end":"2026-06-26T03:39:10.834341Z","steps":["trace[1122855937] 'agreement among raft nodes before linearized reading' (duration: 217.937024ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:26.907007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.663728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-b4clw\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-06-26T03:39:26.90712Z","caller":"traceutil/trace.go:171","msg":"trace[1797134987] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-b4clw; range_end:; response_count:1; response_revision:858; }","duration":"143.856692ms","start":"2026-06-26T03:39:26.763242Z","end":"2026-06-26T03:39:26.907098Z","steps":["trace[1797134987] 'range keys from in-memory index tree' (duration: 143.442273ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:20.974654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.195361ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-06-26T03:41:21.665364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.012844ms","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-06-26T03:41:21.665469Z","caller":"traceutil/trace.go:171","msg":"trace[93197940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2235; }","duration":"108.171588ms","start":"2026-06-26T03:41:21.557278Z","end":"2026-06-26T03:41:21.665449Z","steps":["trace[93197940] 'range keys from in-memory index tree' (duration: 107.82483ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:41:58.875983Z","caller":"traceutil/trace.go:171","msg":"trace[2073189054] transaction","detail":"{read_only:false; response_revision:2540; number_of_response:1; }","duration":"174.527504ms","start":"2026-06-26T03:41:58.701423Z","end":"2026-06-26T03:41:58.87595Z","steps":["trace[2073189054] 'process raft request' (duration: 174.069275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:42:33.433558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.048011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-06-26T03:42:33.433605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.831692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-26T03:42:33.433657Z","caller":"traceutil/trace.go:171","msg":"trace[1169764146] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2777; }","duration":"101.175295ms","start":"2026-06-26T03:42:33.332461Z","end":"2026-06-26T03:42:33.433636Z","steps":["trace[1169764146] 'range keys from in-memory index tree' (duration: 100.910689ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:42:33.433687Z","caller":"traceutil/trace.go:171","msg":"trace[541598399] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2777; }","duration":"105.978075ms","start":"2026-06-26T03:42:33.327691Z","end":"2026-06-26T03:42:33.433669Z","steps":["trace[541598399] 'range keys from in-memory index tree' (duration: 105.609048ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:42:33.612338Z","caller":"traceutil/trace.go:171","msg":"trace[378010895] transaction","detail":"{read_only:false; response_revision:2780; number_of_response:1; }","duration":"119.643482ms","start":"2026-06-26T03:42:33.492654Z","end":"2026-06-26T03:42:33.612297Z","steps":["trace[378010895] 'process raft request' (duration: 90.659824ms)","trace[378010895] 'compare' (duration: 28.750643ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:42:33.88008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.721222ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-26T03:42:33.880205Z","caller":"traceutil/trace.go:171","msg":"trace[679293558] linearizableReadLoop","detail":"{readStateIndex:2892; appliedIndex:2891; }","duration":"120.537462ms","start":"2026-06-26T03:42:33.759648Z","end":"2026-06-26T03:42:33.880185Z","steps":["trace[679293558] 'read index received' (duration: 65.051µs)","trace[679293558] 'applied index is now lower than readState.Index' (duration: 120.470981ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:42:33.880278Z","caller":"traceutil/trace.go:171","msg":"trace[1351063840] transaction","detail":"{read_only:false; response_revision:2781; number_of_response:1; }","duration":"261.41312ms","start":"2026-06-26T03:42:33.618837Z","end":"2026-06-26T03:42:33.880251Z","steps":["trace[1351063840] 'process raft request' (duration: 122.416751ms)","trace[1351063840] 'compare' (duration: 138.568959ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:42:33.880458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.783537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-26T03:42:33.880558Z","caller":"traceutil/trace.go:171","msg":"trace[2078786173] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2781; }","duration":"120.960461ms","start":"2026-06-26T03:42:33.759582Z","end":"2026-06-26T03:42:33.880542Z","steps":["trace[2078786173] 'agreement among raft nodes before linearized reading' (duration: 120.714856ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:44:50.09171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.622848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-00fe01e2-46b6-423e-9b04-d38fa40eb20f\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-06-26T03:44:50.091799Z","caller":"traceutil/trace.go:171","msg":"trace[1723148733] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-00fe01e2-46b6-423e-9b04-d38fa40eb20f; range_end:; response_count:1; response_revision:4578; }","duration":"119.792212ms","start":"2026-06-26T03:44:49.971988Z","end":"2026-06-26T03:44:50.09178Z","steps":["trace[1723148733] 'range keys from in-memory index tree' (duration: 119.410274ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:45:06.702259Z","caller":"traceutil/trace.go:171","msg":"trace[5468928] linearizableReadLoop","detail":"{readStateIndex:5096; appliedIndex:5095; }","duration":"218.336681ms","start":"2026-06-26T03:45:06.483852Z","end":"2026-06-26T03:45:06.702189Z","steps":["trace[5468928] 'read index received' (duration: 213.165781ms)","trace[5468928] 'applied index is now lower than readState.Index' (duration: 5.16989ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:45:06.70285Z","caller":"traceutil/trace.go:171","msg":"trace[1594414404] transaction","detail":"{read_only:false; response_revision:4887; number_of_response:1; }","duration":"403.564568ms","start":"2026-06-26T03:45:06.299222Z","end":"2026-06-26T03:45:06.702787Z","steps":["trace[1594414404] 'process raft request' (duration: 397.873257ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:45:06.702889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.346812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-c47d949f5-n6wv2\" ","response":"range_response_count:1 size:7813"} {"level":"warn","ts":"2026-06-26T03:45:06.702909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.034246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-26T03:45:06.702942Z","caller":"traceutil/trace.go:171","msg":"trace[1670061209] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-c47d949f5-n6wv2; range_end:; response_count:1; response_revision:4887; }","duration":"164.455015ms","start":"2026-06-26T03:45:06.538471Z","end":"2026-06-26T03:45:06.702926Z","steps":["trace[1670061209] 'agreement among raft nodes before linearized reading' (duration: 164.25678ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:45:06.702981Z","caller":"traceutil/trace.go:171","msg":"trace[1573853646] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:4887; }","duration":"219.161228ms","start":"2026-06-26T03:45:06.4838Z","end":"2026-06-26T03:45:06.702962Z","steps":["trace[1573853646] 'agreement among raft nodes before linearized reading' (duration: 218.980765ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:45:06.703018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:45:06.299197Z","time spent":"403.727221ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:45:23.830051Z","caller":"traceutil/trace.go:171","msg":"trace[1997079775] transaction","detail":"{read_only:false; response_revision:5199; number_of_response:1; }","duration":"108.776522ms","start":"2026-06-26T03:45:23.721243Z","end":"2026-06-26T03:45:23.830019Z","steps":["trace[1997079775] 'process raft request' (duration: 108.380854ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:45:24.037307Z","caller":"traceutil/trace.go:171","msg":"trace[1724469836] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"107.923023ms","start":"2026-06-26T03:45:23.929349Z","end":"2026-06-26T03:45:24.037272Z","steps":["trace[1724469836] 'process raft request' (duration: 107.710449ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:48:02.564482Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3115} {"level":"info","ts":"2026-06-26T03:48:02.649697Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3115,"took":"83.413093ms","hash":2555267650,"current-db-size-bytes":61153280,"current-db-size":"61 MB","current-db-size-in-use-bytes":33918976,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-26T03:48:02.649769Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2555267650,"revision":3115,"compact-revision":-1} {"level":"info","ts":"2026-06-26T03:50:51.002327Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002143.wal"} {"level":"warn","ts":"2026-06-26T03:51:06.63622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.043342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-26T03:51:06.636286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.476079ms","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-06-26T03:51:06.636332Z","caller":"traceutil/trace.go:171","msg":"trace[101777420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8261; }","duration":"132.170535ms","start":"2026-06-26T03:51:06.504141Z","end":"2026-06-26T03:51:06.636312Z","steps":["trace[101777420] 'range keys from in-memory index tree' (duration: 131.909139ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:51:06.636373Z","caller":"traceutil/trace.go:171","msg":"trace[76469100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8261; }","duration":"119.586032ms","start":"2026-06-26T03:51:06.516767Z","end":"2026-06-26T03:51:06.636353Z","steps":["trace[76469100] 'range keys from in-memory index tree' (duration: 119.359417ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:51:06.636425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.526691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-06-26T03:51:06.636501Z","caller":"traceutil/trace.go:171","msg":"trace[1950159225] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8261; }","duration":"179.639493ms","start":"2026-06-26T03:51:06.456844Z","end":"2026-06-26T03:51:06.636483Z","steps":["trace[1950159225] 'range keys from in-memory index tree' (duration: 179.311876ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:51:06.636817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.22905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-26T03:51:06.636926Z","caller":"traceutil/trace.go:171","msg":"trace[1284654096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8261; }","duration":"134.943744ms","start":"2026-06-26T03:51:06.501965Z","end":"2026-06-26T03:51:06.636909Z","steps":["trace[1284654096] 'range keys from in-memory index tree' (duration: 134.077686ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:51:06.806156Z","caller":"traceutil/trace.go:171","msg":"trace[1589548353] transaction","detail":"{read_only:false; response_revision:8262; number_of_response:1; }","duration":"165.376114ms","start":"2026-06-26T03:51:06.640741Z","end":"2026-06-26T03:51:06.806117Z","steps":["trace[1589548353] 'process raft request' (duration: 165.098418ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:51:06.833373Z","caller":"traceutil/trace.go:171","msg":"trace[1898412032] transaction","detail":"{read_only:false; response_revision:8263; number_of_response:1; }","duration":"191.374358ms","start":"2026-06-26T03:51:06.641967Z","end":"2026-06-26T03:51:06.833341Z","steps":["trace[1898412032] 'process raft request' (duration: 191.070231ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:51:06.833455Z","caller":"traceutil/trace.go:171","msg":"trace[1998481165] transaction","detail":"{read_only:false; response_revision:8266; number_of_response:1; }","duration":"131.586621ms","start":"2026-06-26T03:51:06.701843Z","end":"2026-06-26T03:51:06.83343Z","steps":["trace[1998481165] 'process raft request' (duration: 131.52835ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:51:06.833577Z","caller":"traceutil/trace.go:171","msg":"trace[2014979191] transaction","detail":"{read_only:false; response_revision:8265; number_of_response:1; }","duration":"188.047166ms","start":"2026-06-26T03:51:06.645487Z","end":"2026-06-26T03:51:06.833534Z","steps":["trace[2014979191] 'process raft request' (duration: 187.816721ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:51:06.83362Z","caller":"traceutil/trace.go:171","msg":"trace[1172325641] transaction","detail":"{read_only:false; response_revision:8264; number_of_response:1; }","duration":"191.063941ms","start":"2026-06-26T03:51:06.64252Z","end":"2026-06-26T03:51:06.833584Z","steps":["trace[1172325641] 'process raft request' (duration: 190.709543ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:51:07.187086Z","caller":"traceutil/trace.go:171","msg":"trace[1452101004] transaction","detail":"{read_only:false; response_revision:8269; number_of_response:1; }","duration":"130.813545ms","start":"2026-06-26T03:51:07.056228Z","end":"2026-06-26T03:51:07.187042Z","steps":["trace[1452101004] 'process raft request' (duration: 110.995076ms)","trace[1452101004] 'compare' (duration: 19.546714ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:51:07.187216Z","caller":"traceutil/trace.go:171","msg":"trace[24780489] linearizableReadLoop","detail":"{readStateIndex:8636; appliedIndex:8635; }","duration":"109.36465ms","start":"2026-06-26T03:51:07.077606Z","end":"2026-06-26T03:51:07.186971Z","steps":["trace[24780489] 'read index received' (duration: 89.678643ms)","trace[24780489] 'applied index is now lower than readState.Index' (duration: 19.684677ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:51:07.18726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.627626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:51:07.187358Z","caller":"traceutil/trace.go:171","msg":"trace[1635950185] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:8269; }","duration":"109.78307ms","start":"2026-06-26T03:51:07.07755Z","end":"2026-06-26T03:51:07.187333Z","steps":["trace[1635950185] 'agreement among raft nodes before linearized reading' (duration: 109.593306ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:51:07.494389Z","caller":"traceutil/trace.go:171","msg":"trace[618431462] transaction","detail":"{read_only:false; response_revision:8270; number_of_response:1; }","duration":"302.750521ms","start":"2026-06-26T03:51:07.191604Z","end":"2026-06-26T03:51:07.494355Z","steps":["trace[618431462] 'process raft request' (duration: 284.581937ms)","trace[618431462] 'compare' (duration: 17.98794ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:51:07.494575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:51:07.191582Z","time spent":"302.898305ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:51:07.504915Z","caller":"traceutil/trace.go:171","msg":"trace[1273322375] linearizableReadLoop","detail":"{readStateIndex:8638; appliedIndex:8636; }","duration":"273.967728ms","start":"2026-06-26T03:51:07.230933Z","end":"2026-06-26T03:51:07.504901Z","steps":["trace[1273322375] 'read index received' (duration: 245.265556ms)","trace[1273322375] 'applied index is now lower than readState.Index' (duration: 28.701422ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:51:07.50505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.121901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-06-26T03:51:07.505071Z","caller":"traceutil/trace.go:171","msg":"trace[852435991] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8271; }","duration":"274.184712ms","start":"2026-06-26T03:51:07.23088Z","end":"2026-06-26T03:51:07.505065Z","steps":["trace[852435991] 'agreement among raft nodes before linearized reading' (duration: 274.06679ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:51:07.505607Z","caller":"traceutil/trace.go:171","msg":"trace[584918918] transaction","detail":"{read_only:false; response_revision:8271; number_of_response:1; }","duration":"310.625011ms","start":"2026-06-26T03:51:07.194952Z","end":"2026-06-26T03:51:07.505577Z","steps":["trace[584918918] 'process raft request' (duration: 309.892585ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:51:07.50584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:51:07.194934Z","time spent":"310.809866ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":536,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T03:51:34.173275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.35263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jspl5\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-06-26T03:51:34.173416Z","caller":"traceutil/trace.go:171","msg":"trace[1522859544] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jspl5; range_end:; response_count:1; response_revision:8501; }","duration":"264.546044ms","start":"2026-06-26T03:51:33.908848Z","end":"2026-06-26T03:51:34.173394Z","steps":["trace[1522859544] 'range keys from in-memory index tree' (duration: 264.048203ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:51:47.364096Z","caller":"traceutil/trace.go:171","msg":"trace[956019018] linearizableReadLoop","detail":"{readStateIndex:9017; appliedIndex:9016; }","duration":"342.010366ms","start":"2026-06-26T03:51:47.022065Z","end":"2026-06-26T03:51:47.364075Z","steps":["trace[956019018] 'read index received' (duration: 341.867182ms)","trace[956019018] 'applied index is now lower than readState.Index' (duration: 142.334µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:51:47.364187Z","caller":"traceutil/trace.go:171","msg":"trace[893503576] transaction","detail":"{read_only:false; response_revision:8629; number_of_response:1; }","duration":"369.081834ms","start":"2026-06-26T03:51:46.99508Z","end":"2026-06-26T03:51:47.364162Z","steps":["trace[893503576] 'process raft request' (duration: 368.86053ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:51:47.364308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.256371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-26T03:51:47.364314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:51:46.99505Z","time spent":"369.189786ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:51:47.364339Z","caller":"traceutil/trace.go:171","msg":"trace[1642721314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8629; }","duration":"342.346813ms","start":"2026-06-26T03:51:47.021985Z","end":"2026-06-26T03:51:47.364332Z","steps":["trace[1642721314] 'agreement among raft nodes before linearized reading' (duration: 342.21009ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:51:47.364366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:51:47.021964Z","time spent":"342.395935ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-26T03:51:47.364514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.544464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-26T03:51:47.364544Z","caller":"traceutil/trace.go:171","msg":"trace[484416132] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8629; }","duration":"211.604525ms","start":"2026-06-26T03:51:47.152932Z","end":"2026-06-26T03:51:47.364536Z","steps":["trace[484416132] 'agreement among raft nodes before linearized reading' (duration: 211.487213ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:51:47.36457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.66116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-26T03:51:47.364641Z","caller":"traceutil/trace.go:171","msg":"trace[1429287808] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8629; }","duration":"318.752931ms","start":"2026-06-26T03:51:47.045873Z","end":"2026-06-26T03:51:47.364626Z","steps":["trace[1429287808] 'agreement among raft nodes before linearized reading' (duration: 318.570387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:51:47.364683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:51:47.045864Z","time spent":"318.809492ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-26T03:51:47.365227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.18445ms","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-06-26T03:51:47.3653Z","caller":"traceutil/trace.go:171","msg":"trace[743539060] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8629; }","duration":"324.879184ms","start":"2026-06-26T03:51:47.040406Z","end":"2026-06-26T03:51:47.365285Z","steps":["trace[743539060] 'agreement among raft nodes before linearized reading' (duration: 323.998186ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:51:47.36534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:51:47.040343Z","time spent":"324.984876ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-26T03:51:48.088333Z","caller":"traceutil/trace.go:171","msg":"trace[668382039] transaction","detail":"{read_only:false; response_revision:8638; number_of_response:1; }","duration":"156.925087ms","start":"2026-06-26T03:51:47.931386Z","end":"2026-06-26T03:51:48.088311Z","steps":["trace[668382039] 'process raft request' (duration: 156.787154ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:52:39.781682Z","caller":"traceutil/trace.go:171","msg":"trace[1357570474] transaction","detail":"{read_only:false; response_revision:9260; number_of_response:1; }","duration":"174.391654ms","start":"2026-06-26T03:52:39.607247Z","end":"2026-06-26T03:52:39.781639Z","steps":["trace[1357570474] 'process raft request' (duration: 174.113298ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:53:02.572123Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6513} {"level":"info","ts":"2026-06-26T03:53:02.674165Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6513,"took":"99.640183ms","hash":2532916837,"current-db-size-bytes":61153280,"current-db-size":"61 MB","current-db-size-in-use-bytes":27299840,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-26T03:53:02.674258Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2532916837,"revision":6513,"compact-revision":3115} {"level":"info","ts":"2026-06-26T03:53:15.053453Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7c53a18098324dc9","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-26T03:53:15.059514Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-26T03:53:15.059656Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-26T03:56:12.96855Z","caller":"traceutil/trace.go:171","msg":"trace[682060243] transaction","detail":"{read_only:false; response_revision:11408; number_of_response:1; }","duration":"129.612241ms","start":"2026-06-26T03:56:12.838899Z","end":"2026-06-26T03:56:12.968511Z","steps":["trace[682060243] 'process raft request' (duration: 129.313714ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:58:02.578896Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9468} {"level":"info","ts":"2026-06-26T03:58:02.664252Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9468,"took":"82.642721ms","hash":1677455741,"current-db-size-bytes":61153280,"current-db-size":"61 MB","current-db-size-in-use-bytes":29478912,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-26T03:58:02.664307Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1677455741,"revision":9468,"compact-revision":6513} {"level":"warn","ts":"2026-06-26T04:02:40.916317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.298776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T04:02:40.916409Z","caller":"traceutil/trace.go:171","msg":"trace[421909795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14555; }","duration":"301.449449ms","start":"2026-06-26T04:02:40.614939Z","end":"2026-06-26T04:02:40.916388Z","steps":["trace[421909795] 'range keys from in-memory index tree' (duration: 301.209375ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:02:40.916446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:02:40.614914Z","time spent":"301.522632ms","remote":"127.0.0.1:38010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-26T04:02:40.917062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.024617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:82 size:714930"} {"level":"info","ts":"2026-06-26T04:02:40.917128Z","caller":"traceutil/trace.go:171","msg":"trace[84123171] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:82; response_revision:14555; }","duration":"225.122858ms","start":"2026-06-26T04:02:40.691991Z","end":"2026-06-26T04:02:40.917114Z","steps":["trace[84123171] 'range keys from in-memory index tree' (duration: 224.099467ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:02:41.173402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.344792ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-26T04:02:41.173534Z","caller":"traceutil/trace.go:171","msg":"trace[2075313692] linearizableReadLoop","detail":"{readStateIndex:15234; appliedIndex:15233; }","duration":"268.939826ms","start":"2026-06-26T04:02:40.904575Z","end":"2026-06-26T04:02:41.173514Z","steps":["trace[2075313692] 'read index received' (duration: 81.41119ms)","trace[2075313692] 'applied index is now lower than readState.Index' (duration: 187.526356ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:02:41.174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.917983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-26T04:02:41.174057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.725146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-26T04:02:41.174102Z","caller":"traceutil/trace.go:171","msg":"trace[1704619844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14555; }","duration":"261.059247ms","start":"2026-06-26T04:02:40.913028Z","end":"2026-06-26T04:02:41.174087Z","steps":["trace[1704619844] 'agreement among raft nodes before linearized reading' (duration: 260.811861ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:02:41.17414Z","caller":"traceutil/trace.go:171","msg":"trace[567918211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14555; }","duration":"191.859699ms","start":"2026-06-26T04:02:40.982259Z","end":"2026-06-26T04:02:41.174118Z","steps":["trace[567918211] 'agreement among raft nodes before linearized reading' (duration: 191.608244ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:02:41.17423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.437993ms","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-06-26T04:02:41.174202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.68068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-26T04:02:41.174254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.538769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-06-26T04:02:41.174289Z","caller":"traceutil/trace.go:171","msg":"trace[487857304] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14555; }","duration":"254.551605ms","start":"2026-06-26T04:02:40.919721Z","end":"2026-06-26T04:02:41.174272Z","steps":["trace[487857304] 'agreement among raft nodes before linearized reading' (duration: 254.368262ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:02:41.174313Z","caller":"traceutil/trace.go:171","msg":"trace[1791390988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14555; }","duration":"256.800373ms","start":"2026-06-26T04:02:40.917492Z","end":"2026-06-26T04:02:41.174293Z","steps":["trace[1791390988] 'agreement among raft nodes before linearized reading' (duration: 256.636669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:02:41.174243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.226161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-26T04:02:41.173882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.283825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-26T04:02:41.174495Z","caller":"traceutil/trace.go:171","msg":"trace[984935431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14555; }","duration":"256.496987ms","start":"2026-06-26T04:02:40.917932Z","end":"2026-06-26T04:02:41.174429Z","steps":["trace[984935431] 'agreement among raft nodes before linearized reading' (duration: 256.150339ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:02:41.174324Z","caller":"traceutil/trace.go:171","msg":"trace[443153010] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14555; }","duration":"189.613891ms","start":"2026-06-26T04:02:40.984689Z","end":"2026-06-26T04:02:41.174303Z","steps":["trace[443153010] 'agreement among raft nodes before linearized reading' (duration: 189.435747ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:02:41.174556Z","caller":"traceutil/trace.go:171","msg":"trace[694645813] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14555; }","duration":"269.985339ms","start":"2026-06-26T04:02:40.904538Z","end":"2026-06-26T04:02:41.174523Z","steps":["trace[694645813] 'agreement among raft nodes before linearized reading' (duration: 269.154271ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:02:41.1747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.113445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:82 size:714930"} {"level":"info","ts":"2026-06-26T04:02:41.174791Z","caller":"traceutil/trace.go:171","msg":"trace[1049998890] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:82; response_revision:14555; }","duration":"186.261798ms","start":"2026-06-26T04:02:40.988508Z","end":"2026-06-26T04:02:41.17477Z","steps":["trace[1049998890] 'agreement among raft nodes before linearized reading' (duration: 185.40101ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:03:02.58527Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12335} {"level":"info","ts":"2026-06-26T04:03:02.677399Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12335,"took":"89.398144ms","hash":3943731108,"current-db-size-bytes":61153280,"current-db-size":"61 MB","current-db-size-in-use-bytes":27107328,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-26T04:03:02.677472Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3943731108,"revision":12335,"compact-revision":9468} {"level":"info","ts":"2026-06-26T04:04:20.566056Z","caller":"traceutil/trace.go:171","msg":"trace[149542058] transaction","detail":"{read_only:false; response_revision:15884; number_of_response:1; }","duration":"104.053556ms","start":"2026-06-26T04:04:20.461972Z","end":"2026-06-26T04:04:20.566026Z","steps":["trace[149542058] 'process raft request' (duration: 103.675888ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:04:20.737642Z","caller":"traceutil/trace.go:171","msg":"trace[216004743] transaction","detail":"{read_only:false; response_revision:15885; number_of_response:1; }","duration":"112.203406ms","start":"2026-06-26T04:04:20.625417Z","end":"2026-06-26T04:04:20.73762Z","steps":["trace[216004743] 'process raft request' (duration: 112.017072ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:04:26.809084Z","caller":"traceutil/trace.go:171","msg":"trace[92603367] linearizableReadLoop","detail":"{readStateIndex:16661; appliedIndex:16660; }","duration":"194.583153ms","start":"2026-06-26T04:04:26.614477Z","end":"2026-06-26T04:04:26.80906Z","steps":["trace[92603367] 'read index received' (duration: 194.430159ms)","trace[92603367] 'applied index is now lower than readState.Index' (duration: 151.884µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:04:26.809265Z","caller":"traceutil/trace.go:171","msg":"trace[1274393558] transaction","detail":"{read_only:false; response_revision:15925; number_of_response:1; }","duration":"213.549692ms","start":"2026-06-26T04:04:26.595702Z","end":"2026-06-26T04:04:26.809252Z","steps":["trace[1274393558] 'process raft request' (duration: 213.207125ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:04:26.809941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.441672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T04:04:26.810033Z","caller":"traceutil/trace.go:171","msg":"trace[89234065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15925; }","duration":"195.563306ms","start":"2026-06-26T04:04:26.614452Z","end":"2026-06-26T04:04:26.810015Z","steps":["trace[89234065] 'agreement among raft nodes before linearized reading' (duration: 195.431553ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:07:12.39424Z","caller":"traceutil/trace.go:171","msg":"trace[344781980] transaction","detail":"{read_only:false; number_of_response:0; response_revision:17490; }","duration":"105.59514ms","start":"2026-06-26T04:07:12.288588Z","end":"2026-06-26T04:07:12.394183Z","steps":["trace[344781980] 'process raft request' (duration: 105.475927ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:08:02.594075Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15015} {"level":"info","ts":"2026-06-26T04:08:02.696426Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15015,"took":"99.036501ms","hash":2322370138,"current-db-size-bytes":61153280,"current-db-size":"61 MB","current-db-size-in-use-bytes":32178176,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-26T04:08:02.696507Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2322370138,"revision":15015,"compact-revision":12335} {"level":"info","ts":"2026-06-26T04:10:01.610615Z","caller":"traceutil/trace.go:171","msg":"trace[1097378494] linearizableReadLoop","detail":"{readStateIndex:19830; appliedIndex:19829; }","duration":"144.841304ms","start":"2026-06-26T04:10:01.46575Z","end":"2026-06-26T04:10:01.610591Z","steps":["trace[1097378494] 'read index received' (duration: 144.656281ms)","trace[1097378494] 'applied index is now lower than readState.Index' (duration: 184.113µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:10:01.611098Z","caller":"traceutil/trace.go:171","msg":"trace[687220812] transaction","detail":"{read_only:false; response_revision:18945; number_of_response:1; }","duration":"163.611141ms","start":"2026-06-26T04:10:01.447465Z","end":"2026-06-26T04:10:01.611076Z","steps":["trace[687220812] 'process raft request' (duration: 162.990388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:10:01.61118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.420637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-26T04:10:01.611271Z","caller":"traceutil/trace.go:171","msg":"trace[664034650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18945; }","duration":"145.55262ms","start":"2026-06-26T04:10:01.465696Z","end":"2026-06-26T04:10:01.611249Z","steps":["trace[664034650] 'agreement among raft nodes before linearized reading' (duration: 145.048969ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:10:07.162942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.195547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:10:07.163141Z","caller":"traceutil/trace.go:171","msg":"trace[1860530625] linearizableReadLoop","detail":"{readStateIndex:19872; appliedIndex:19870; }","duration":"259.81591ms","start":"2026-06-26T04:10:06.903306Z","end":"2026-06-26T04:10:07.163122Z","steps":["trace[1860530625] 'read index received' (duration: 190.175118ms)","trace[1860530625] 'applied index is now lower than readState.Index' (duration: 69.639661ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:10:07.163245Z","caller":"traceutil/trace.go:171","msg":"trace[1241924376] transaction","detail":"{read_only:false; response_revision:18985; number_of_response:1; }","duration":"346.353748ms","start":"2026-06-26T04:10:06.816881Z","end":"2026-06-26T04:10:07.163235Z","steps":["trace[1241924376] 'process raft request' (duration: 346.179914ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:10:07.163311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:10:06.816852Z","time spent":"346.42575ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:10:07.163294Z","caller":"traceutil/trace.go:171","msg":"trace[980598626] transaction","detail":"{read_only:false; response_revision:18984; number_of_response:1; }","duration":"532.526149ms","start":"2026-06-26T04:10:06.630712Z","end":"2026-06-26T04:10:07.163238Z","steps":["trace[980598626] 'process raft request' (duration: 213.938904ms)","trace[980598626] 'compare' (duration: 318.049814ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:10:07.163504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:10:06.630693Z","time spent":"532.693362ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:10:07.16399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.501862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11498"} {"level":"warn","ts":"2026-06-26T04:10:07.164425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.249809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-26T04:10:07.164481Z","caller":"traceutil/trace.go:171","msg":"trace[832487936] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:18985; }","duration":"261.23148ms","start":"2026-06-26T04:10:06.903234Z","end":"2026-06-26T04:10:07.164466Z","steps":["trace[832487936] 'agreement among raft nodes before linearized reading' (duration: 260.26892ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:10:07.164689Z","caller":"traceutil/trace.go:171","msg":"trace[1889014008] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18985; }","duration":"247.586884ms","start":"2026-06-26T04:10:06.916461Z","end":"2026-06-26T04:10:07.164048Z","steps":["trace[1889014008] 'agreement among raft nodes before linearized reading' (duration: 247.37191ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:10:07.300502Z","caller":"traceutil/trace.go:171","msg":"trace[2109847287] transaction","detail":"{read_only:false; response_revision:18986; number_of_response:1; }","duration":"108.110537ms","start":"2026-06-26T04:10:07.192367Z","end":"2026-06-26T04:10:07.300478Z","steps":["trace[2109847287] 'process raft request' (duration: 100.604944ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:10:07.348392Z","caller":"traceutil/trace.go:171","msg":"trace[150188834] transaction","detail":"{read_only:false; response_revision:18987; number_of_response:1; }","duration":"109.273551ms","start":"2026-06-26T04:10:07.239097Z","end":"2026-06-26T04:10:07.348371Z","steps":["trace[150188834] 'process raft request' (duration: 108.871803ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:10:08.918781Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7c53a18098324dc9","local-member-applied-index":20003,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-26T04:10:08.936555Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-06-26T04:10:08.936686Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-06-26T04:13:02.601461Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17899} {"level":"info","ts":"2026-06-26T04:13:02.713332Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17899,"took":"108.500925ms","hash":2931004138,"current-db-size-bytes":61153280,"current-db-size":"61 MB","current-db-size-in-use-bytes":32579584,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-26T04:13:02.713389Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2931004138,"revision":17899,"compact-revision":15015} {"level":"warn","ts":"2026-06-26T04:17:01.608373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.240846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:17:01.608517Z","caller":"traceutil/trace.go:171","msg":"trace[1859422929] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:22991; }","duration":"110.427839ms","start":"2026-06-26T04:17:01.498047Z","end":"2026-06-26T04:17:01.608475Z","steps":["trace[1859422929] 'count revisions from in-memory index tree' (duration: 110.140184ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:17:17.206825Z","caller":"traceutil/trace.go:171","msg":"trace[587509787] transaction","detail":"{read_only:false; response_revision:23093; number_of_response:1; }","duration":"263.601093ms","start":"2026-06-26T04:17:16.943192Z","end":"2026-06-26T04:17:17.206793Z","steps":["trace[587509787] 'process raft request' (duration: 201.819157ms)","trace[587509787] 'compare' (duration: 61.615353ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:17:17.262783Z","caller":"traceutil/trace.go:171","msg":"trace[1853516414] transaction","detail":"{read_only:false; response_revision:23094; number_of_response:1; }","duration":"316.397136ms","start":"2026-06-26T04:17:16.946353Z","end":"2026-06-26T04:17:17.26275Z","steps":["trace[1853516414] 'process raft request' (duration: 316.239623ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:17:17.263002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:17:16.946328Z","time spent":"316.588111ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:17:17.362692Z","caller":"traceutil/trace.go:171","msg":"trace[136284768] transaction","detail":"{read_only:false; response_revision:23095; number_of_response:1; }","duration":"150.484296ms","start":"2026-06-26T04:17:17.212169Z","end":"2026-06-26T04:17:17.362654Z","steps":["trace[136284768] 'process raft request' (duration: 142.222097ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:18:02.608234Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20977} {"level":"info","ts":"2026-06-26T04:18:02.774541Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20977,"took":"161.012534ms","hash":3195585164,"current-db-size-bytes":61153280,"current-db-size":"61 MB","current-db-size-in-use-bytes":33165312,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-26T04:18:02.774619Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3195585164,"revision":20977,"compact-revision":17899} {"level":"info","ts":"2026-06-26T04:20:42.309157Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068bd.wal"} {"level":"info","ts":"2026-06-26T04:20:46.260361Z","caller":"traceutil/trace.go:171","msg":"trace[952577896] transaction","detail":"{read_only:false; response_revision:25717; number_of_response:1; }","duration":"127.239414ms","start":"2026-06-26T04:20:46.133091Z","end":"2026-06-26T04:20:46.260331Z","steps":["trace[952577896] 'process raft request' (duration: 127.003019ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:20:46.584752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.060329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-06-26T04:20:46.584836Z","caller":"traceutil/trace.go:171","msg":"trace[450351559] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25718; }","duration":"114.155821ms","start":"2026-06-26T04:20:46.470659Z","end":"2026-06-26T04:20:46.584815Z","steps":["trace[450351559] 'range keys from in-memory index tree' (duration: 113.895255ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:20:46.584984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.355427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-06-26T04:20:46.585058Z","caller":"traceutil/trace.go:171","msg":"trace[1569687922] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:25718; }","duration":"158.48691ms","start":"2026-06-26T04:20:46.426555Z","end":"2026-06-26T04:20:46.585042Z","steps":["trace[1569687922] 'range keys from in-memory index tree' (duration: 158.153263ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:23:02.618196Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23657} {"level":"info","ts":"2026-06-26T04:23:02.743566Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23657,"took":"119.108726ms","hash":3081728030,"current-db-size-bytes":61153280,"current-db-size":"61 MB","current-db-size-in-use-bytes":43675648,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-06-26T04:23:02.74364Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3081728030,"revision":23657,"compact-revision":20977} {"level":"info","ts":"2026-06-26T04:24:17.165538Z","caller":"traceutil/trace.go:171","msg":"trace[1759931543] transaction","detail":"{read_only:false; response_revision:28093; number_of_response:1; }","duration":"190.462418ms","start":"2026-06-26T04:24:16.975041Z","end":"2026-06-26T04:24:17.165503Z","steps":["trace[1759931543] 'process raft request' (duration: 91.700613ms)","trace[1759931543] 'compare' (duration: 98.409517ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:17.386645Z","caller":"traceutil/trace.go:171","msg":"trace[1488264147] transaction","detail":"{read_only:false; response_revision:28094; number_of_response:1; }","duration":"277.598523ms","start":"2026-06-26T04:24:17.108992Z","end":"2026-06-26T04:24:17.386591Z","steps":["trace[1488264147] 'process raft request' (duration: 267.280399ms)","trace[1488264147] 'compare' (duration: 10.084659ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:17.59833Z","caller":"traceutil/trace.go:171","msg":"trace[479508686] linearizableReadLoop","detail":"{readStateIndex:29380; appliedIndex:29378; }","duration":"427.732288ms","start":"2026-06-26T04:24:17.170578Z","end":"2026-06-26T04:24:17.59831Z","steps":["trace[479508686] 'read index received' (duration: 205.710288ms)","trace[479508686] 'applied index is now lower than readState.Index' (duration: 222.02133ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:17.598423Z","caller":"traceutil/trace.go:171","msg":"trace[862584733] transaction","detail":"{read_only:false; response_revision:28095; number_of_response:1; }","duration":"481.105082ms","start":"2026-06-26T04:24:17.117307Z","end":"2026-06-26T04:24:17.598412Z","steps":["trace[862584733] 'process raft request' (duration: 386.237061ms)","trace[862584733] 'compare' (duration: 94.536984ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:24:17.598517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:24:17.11728Z","time spent":"481.165343ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:24:17.598597Z","caller":"traceutil/trace.go:171","msg":"trace[1284928591] transaction","detail":"{read_only:false; response_revision:28096; number_of_response:1; }","duration":"277.067641ms","start":"2026-06-26T04:24:17.321512Z","end":"2026-06-26T04:24:17.598579Z","steps":["trace[1284928591] 'process raft request' (duration: 276.739534ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:17.598689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.114656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-06-26T04:24:17.598718Z","caller":"traceutil/trace.go:171","msg":"trace[443020373] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28096; }","duration":"428.182737ms","start":"2026-06-26T04:24:17.170527Z","end":"2026-06-26T04:24:17.59871Z","steps":["trace[443020373] 'agreement among raft nodes before linearized reading' (duration: 428.086395ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:17.598739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:24:17.170508Z","time spent":"428.226038ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":592,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-06-26T04:24:17.621068Z","caller":"traceutil/trace.go:171","msg":"trace[479507624] transaction","detail":"{read_only:false; response_revision:28097; number_of_response:1; }","duration":"168.217647ms","start":"2026-06-26T04:24:17.452805Z","end":"2026-06-26T04:24:17.621022Z","steps":["trace[479507624] 'process raft request' (duration: 167.741927ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:17.621142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.464688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-06-26T04:24:17.621216Z","caller":"traceutil/trace.go:171","msg":"trace[814926860] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:28097; }","duration":"184.589721ms","start":"2026-06-26T04:24:17.436606Z","end":"2026-06-26T04:24:17.621196Z","steps":["trace[814926860] 'agreement among raft nodes before linearized reading' (duration: 184.330985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:17.621208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.501288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11826"} {"level":"info","ts":"2026-06-26T04:24:17.621326Z","caller":"traceutil/trace.go:171","msg":"trace[1802961972] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:28097; }","duration":"208.655123ms","start":"2026-06-26T04:24:17.412637Z","end":"2026-06-26T04:24:17.621292Z","steps":["trace[1802961972] 'agreement among raft nodes before linearized reading' (duration: 208.365995ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:17.816243Z","caller":"traceutil/trace.go:171","msg":"trace[1987784153] linearizableReadLoop","detail":"{readStateIndex:29383; appliedIndex:29382; }","duration":"195.506528ms","start":"2026-06-26T04:24:17.620713Z","end":"2026-06-26T04:24:17.816219Z","steps":["trace[1987784153] 'read index received' (duration: 108.115428ms)","trace[1987784153] 'applied index is now lower than readState.Index' (duration: 87.38997ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:17.816365Z","caller":"traceutil/trace.go:171","msg":"trace[1250831061] transaction","detail":"{read_only:false; response_revision:28098; number_of_response:1; }","duration":"211.483153ms","start":"2026-06-26T04:24:17.604868Z","end":"2026-06-26T04:24:17.816352Z","steps":["trace[1250831061] 'process raft request' (duration: 123.640854ms)","trace[1250831061] 'compare' (duration: 87.547412ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:24:17.816436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.750154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:24:17.816473Z","caller":"traceutil/trace.go:171","msg":"trace[974947435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28098; }","duration":"202.822955ms","start":"2026-06-26T04:24:17.61364Z","end":"2026-06-26T04:24:17.816463Z","steps":["trace[974947435] 'agreement among raft nodes before linearized reading' (duration: 202.750313ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:17.817284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.859729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-bbcffa11-21bd-495d-8d32-7ebf5c4b0f6a\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-06-26T04:24:17.817338Z","caller":"traceutil/trace.go:171","msg":"trace[1176255556] range","detail":"{range_begin:/registry/persistentvolumes/pvc-bbcffa11-21bd-495d-8d32-7ebf5c4b0f6a; range_end:; response_count:1; response_revision:28099; }","duration":"192.93151ms","start":"2026-06-26T04:24:17.624393Z","end":"2026-06-26T04:24:17.817325Z","steps":["trace[1176255556] 'agreement among raft nodes before linearized reading' (duration: 192.750686ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:17.817448Z","caller":"traceutil/trace.go:171","msg":"trace[541059422] transaction","detail":"{read_only:false; response_revision:28099; number_of_response:1; }","duration":"187.550366ms","start":"2026-06-26T04:24:17.629884Z","end":"2026-06-26T04:24:17.817434Z","steps":["trace[541059422] 'process raft request' (duration: 187.146226ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:17.817804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.147086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11826"} {"level":"info","ts":"2026-06-26T04:24:17.817848Z","caller":"traceutil/trace.go:171","msg":"trace[1270398046] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:28099; }","duration":"187.206498ms","start":"2026-06-26T04:24:17.630635Z","end":"2026-06-26T04:24:17.817841Z","steps":["trace[1270398046] 'agreement among raft nodes before linearized reading' (duration: 187.109306ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:22.195391Z","caller":"traceutil/trace.go:171","msg":"trace[1059319546] linearizableReadLoop","detail":"{readStateIndex:29454; appliedIndex:29453; }","duration":"139.155858ms","start":"2026-06-26T04:24:22.056196Z","end":"2026-06-26T04:24:22.195352Z","steps":["trace[1059319546] 'read index received' (duration: 71.598598ms)","trace[1059319546] 'applied index is now lower than readState.Index' (duration: 67.55583ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:24:22.19577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.562677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-06-26T04:24:22.195825Z","caller":"traceutil/trace.go:171","msg":"trace[1502564295] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28167; }","duration":"139.667609ms","start":"2026-06-26T04:24:22.056139Z","end":"2026-06-26T04:24:22.195806Z","steps":["trace[1502564295] 'agreement among raft nodes before linearized reading' (duration: 139.346222ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:22.195787Z","caller":"traceutil/trace.go:171","msg":"trace[551048175] transaction","detail":"{read_only:false; response_revision:28167; number_of_response:1; }","duration":"192.775688ms","start":"2026-06-26T04:24:22.002919Z","end":"2026-06-26T04:24:22.195695Z","steps":["trace[551048175] 'process raft request' (duration: 124.87679ms)","trace[551048175] 'compare' (duration: 67.288015ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:24:22.487817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.054836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:24:22.487932Z","caller":"traceutil/trace.go:171","msg":"trace[1434490984] linearizableReadLoop","detail":"{readStateIndex:29455; appliedIndex:29454; }","duration":"255.585377ms","start":"2026-06-26T04:24:22.232328Z","end":"2026-06-26T04:24:22.487913Z","steps":["trace[1434490984] 'read index received' (duration: 67.323986ms)","trace[1434490984] 'applied index is now lower than readState.Index' (duration: 188.260101ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:22.488009Z","caller":"traceutil/trace.go:171","msg":"trace[1717847838] transaction","detail":"{read_only:false; response_revision:28168; number_of_response:1; }","duration":"283.910579ms","start":"2026-06-26T04:24:22.204086Z","end":"2026-06-26T04:24:22.487996Z","steps":["trace[1717847838] 'process raft request' (duration: 95.554597ms)","trace[1717847838] 'compare' (duration: 187.905513ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:24:22.488096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.739951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:24:22.488218Z","caller":"traceutil/trace.go:171","msg":"trace[1106298729] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:28168; }","duration":"255.927814ms","start":"2026-06-26T04:24:22.232259Z","end":"2026-06-26T04:24:22.488186Z","steps":["trace[1106298729] 'agreement among raft nodes before linearized reading' (duration: 255.73358ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:22.489899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.996886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-26T04:24:22.490012Z","caller":"traceutil/trace.go:171","msg":"trace[69572990] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28169; }","duration":"257.15121ms","start":"2026-06-26T04:24:22.232838Z","end":"2026-06-26T04:24:22.489989Z","steps":["trace[69572990] 'agreement among raft nodes before linearized reading' (duration: 256.837073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:22.490252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.02053ms","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-06-26T04:24:22.490317Z","caller":"traceutil/trace.go:171","msg":"trace[763956120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28169; }","duration":"215.155763ms","start":"2026-06-26T04:24:22.275144Z","end":"2026-06-26T04:24:22.4903Z","steps":["trace[763956120] 'agreement among raft nodes before linearized reading' (duration: 214.894236ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:22.490496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.797017ms","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-06-26T04:24:22.490552Z","caller":"traceutil/trace.go:171","msg":"trace[247412216] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28169; }","duration":"221.865208ms","start":"2026-06-26T04:24:22.268668Z","end":"2026-06-26T04:24:22.490533Z","steps":["trace[247412216] 'agreement among raft nodes before linearized reading' (duration: 221.748096ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:22.490637Z","caller":"traceutil/trace.go:171","msg":"trace[1485169110] transaction","detail":"{read_only:false; response_revision:28169; number_of_response:1; }","duration":"168.666958ms","start":"2026-06-26T04:24:22.321956Z","end":"2026-06-26T04:24:22.490623Z","steps":["trace[1485169110] 'process raft request' (duration: 167.474962ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:22.490764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.217528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-26T04:24:22.490804Z","caller":"traceutil/trace.go:171","msg":"trace[34671975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28169; }","duration":"224.28625ms","start":"2026-06-26T04:24:22.266509Z","end":"2026-06-26T04:24:22.490795Z","steps":["trace[34671975] 'agreement among raft nodes before linearized reading' (duration: 224.187278ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:22.491005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.601389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-26T04:24:22.491045Z","caller":"traceutil/trace.go:171","msg":"trace[578213649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28169; }","duration":"238.702191ms","start":"2026-06-26T04:24:22.252335Z","end":"2026-06-26T04:24:22.491037Z","steps":["trace[578213649] 'agreement among raft nodes before linearized reading' (duration: 238.605799ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:22.82844Z","caller":"traceutil/trace.go:171","msg":"trace[1931207591] linearizableReadLoop","detail":"{readStateIndex:29462; appliedIndex:29457; }","duration":"214.006657ms","start":"2026-06-26T04:24:22.614417Z","end":"2026-06-26T04:24:22.828424Z","steps":["trace[1931207591] 'read index received' (duration: 178.792266ms)","trace[1931207591] 'applied index is now lower than readState.Index' (duration: 35.213491ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:22.828523Z","caller":"traceutil/trace.go:171","msg":"trace[907420346] transaction","detail":"{read_only:false; response_revision:28171; number_of_response:1; }","duration":"330.864664ms","start":"2026-06-26T04:24:22.497568Z","end":"2026-06-26T04:24:22.828432Z","steps":["trace[907420346] 'process raft request' (duration: 295.698884ms)","trace[907420346] 'compare' (duration: 34.256361ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:22.828572Z","caller":"traceutil/trace.go:171","msg":"trace[634655555] transaction","detail":"{read_only:false; response_revision:28172; number_of_response:1; }","duration":"330.853044ms","start":"2026-06-26T04:24:22.497707Z","end":"2026-06-26T04:24:22.82856Z","steps":["trace[634655555] 'process raft request' (duration: 330.593928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:22.828654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:24:22.497684Z","time spent":"330.910944ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:24:22.828703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:24:22.49753Z","time spent":"331.072779ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":536,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:24:22.8287Z","caller":"traceutil/trace.go:171","msg":"trace[1320779069] transaction","detail":"{read_only:false; response_revision:28175; number_of_response:1; }","duration":"330.623639ms","start":"2026-06-26T04:24:22.498043Z","end":"2026-06-26T04:24:22.828666Z","steps":["trace[1320779069] 'process raft request' (duration: 330.352623ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:22.828756Z","caller":"traceutil/trace.go:171","msg":"trace[1123462486] transaction","detail":"{read_only:false; response_revision:28174; number_of_response:1; }","duration":"330.765121ms","start":"2026-06-26T04:24:22.497967Z","end":"2026-06-26T04:24:22.828732Z","steps":["trace[1123462486] 'process raft request' (duration: 330.400204ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:22.828767Z","caller":"traceutil/trace.go:171","msg":"trace[1198401472] transaction","detail":"{read_only:false; response_revision:28173; number_of_response:1; }","duration":"330.857843ms","start":"2026-06-26T04:24:22.49787Z","end":"2026-06-26T04:24:22.828728Z","steps":["trace[1198401472] 'process raft request' (duration: 330.467965ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:22.828862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:24:22.498035Z","time spent":"330.738551ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:24:22.828872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:24:22.497959Z","time spent":"330.841573ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:24:22.828898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:24:22.497862Z","time spent":"330.966136ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:24:22.828896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.484447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:24:22.828934Z","caller":"traceutil/trace.go:171","msg":"trace[1904780520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28175; }","duration":"214.548538ms","start":"2026-06-26T04:24:22.614375Z","end":"2026-06-26T04:24:22.828924Z","steps":["trace[1904780520] 'agreement among raft nodes before linearized reading' (duration: 214.471577ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:23.008638Z","caller":"traceutil/trace.go:171","msg":"trace[1211472958] transaction","detail":"{read_only:false; response_revision:28176; number_of_response:1; }","duration":"167.147674ms","start":"2026-06-26T04:24:22.841456Z","end":"2026-06-26T04:24:23.008604Z","steps":["trace[1211472958] 'process raft request' (duration: 152.480106ms)","trace[1211472958] 'compare' (duration: 14.32177ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:24:23.561589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.289818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-26T04:24:23.562272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.373236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:24:23.562372Z","caller":"traceutil/trace.go:171","msg":"trace[1798954919] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:28179; }","duration":"165.630321ms","start":"2026-06-26T04:24:23.396718Z","end":"2026-06-26T04:24:23.562349Z","steps":["trace[1798954919] 'count revisions from in-memory index tree' (duration: 165.158511ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:23.562891Z","caller":"traceutil/trace.go:171","msg":"trace[1643096120] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:28179; }","duration":"229.462192ms","start":"2026-06-26T04:24:23.33221Z","end":"2026-06-26T04:24:23.561672Z","steps":["trace[1643096120] 'count revisions from in-memory index tree' (duration: 229.155346ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:23.676659Z","caller":"traceutil/trace.go:171","msg":"trace[1638826811] transaction","detail":"{read_only:false; response_revision:28180; number_of_response:1; }","duration":"264.08293ms","start":"2026-06-26T04:24:23.412536Z","end":"2026-06-26T04:24:23.676619Z","steps":["trace[1638826811] 'process raft request' (duration: 263.094409ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:23.676789Z","caller":"traceutil/trace.go:171","msg":"trace[1914740142] linearizableReadLoop","detail":"{readStateIndex:29467; appliedIndex:29466; }","duration":"191.689754ms","start":"2026-06-26T04:24:23.48507Z","end":"2026-06-26T04:24:23.67676Z","steps":["trace[1914740142] 'read index received' (duration: 190.647512ms)","trace[1914740142] 'applied index is now lower than readState.Index' (duration: 1.040532ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:24:23.677082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.93508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:24:23.677261Z","caller":"traceutil/trace.go:171","msg":"trace[1248419127] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:28180; }","duration":"192.185415ms","start":"2026-06-26T04:24:23.485034Z","end":"2026-06-26T04:24:23.67722Z","steps":["trace[1248419127] 'agreement among raft nodes before linearized reading' (duration: 191.811147ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:23.69154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.642263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:24:23.691637Z","caller":"traceutil/trace.go:171","msg":"trace[233375497] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:28182; }","duration":"185.787387ms","start":"2026-06-26T04:24:23.505824Z","end":"2026-06-26T04:24:23.691611Z","steps":["trace[233375497] 'agreement among raft nodes before linearized reading' (duration: 185.595113ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:24.284712Z","caller":"traceutil/trace.go:171","msg":"trace[1456186191] linearizableReadLoop","detail":"{readStateIndex:29471; appliedIndex:29470; }","duration":"119.444923ms","start":"2026-06-26T04:24:24.165235Z","end":"2026-06-26T04:24:24.28468Z","steps":["trace[1456186191] 'read index received' (duration: 119.069075ms)","trace[1456186191] 'applied index is now lower than readState.Index' (duration: 374.458µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:24.284746Z","caller":"traceutil/trace.go:171","msg":"trace[375065823] transaction","detail":"{read_only:false; response_revision:28184; number_of_response:1; }","duration":"162.514213ms","start":"2026-06-26T04:24:24.12219Z","end":"2026-06-26T04:24:24.284704Z","steps":["trace[375065823] 'process raft request' (duration: 162.226817ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:24.286183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.922335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-26T04:24:24.286292Z","caller":"traceutil/trace.go:171","msg":"trace[1875612586] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:28184; }","duration":"121.086279ms","start":"2026-06-26T04:24:24.165189Z","end":"2026-06-26T04:24:24.286275Z","steps":["trace[1875612586] 'agreement among raft nodes before linearized reading' (duration: 119.596106ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:24.493759Z","caller":"traceutil/trace.go:171","msg":"trace[804686461] transaction","detail":"{read_only:false; response_revision:28185; number_of_response:1; }","duration":"199.647947ms","start":"2026-06-26T04:24:24.294085Z","end":"2026-06-26T04:24:24.493733Z","steps":["trace[804686461] 'process raft request' (duration: 188.090638ms)","trace[804686461] 'compare' (duration: 11.182622ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:24.649202Z","caller":"traceutil/trace.go:171","msg":"trace[8411379] transaction","detail":"{read_only:false; response_revision:28187; number_of_response:1; }","duration":"136.424539ms","start":"2026-06-26T04:24:24.512748Z","end":"2026-06-26T04:24:24.649172Z","steps":["trace[8411379] 'process raft request' (duration: 135.016069ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:25.012809Z","caller":"traceutil/trace.go:171","msg":"trace[518337255] transaction","detail":"{read_only:false; response_revision:28189; number_of_response:1; }","duration":"156.276899ms","start":"2026-06-26T04:24:24.856502Z","end":"2026-06-26T04:24:25.012779Z","steps":["trace[518337255] 'process raft request' (duration: 156.062025ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:25.02651Z","caller":"traceutil/trace.go:171","msg":"trace[791905290] transaction","detail":"{read_only:false; response_revision:28190; number_of_response:1; }","duration":"169.983066ms","start":"2026-06-26T04:24:24.856506Z","end":"2026-06-26T04:24:25.026489Z","steps":["trace[791905290] 'process raft request' (duration: 169.646789ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:25.026536Z","caller":"traceutil/trace.go:171","msg":"trace[2079812402] transaction","detail":"{read_only:false; response_revision:28191; number_of_response:1; }","duration":"169.849573ms","start":"2026-06-26T04:24:24.856665Z","end":"2026-06-26T04:24:25.026515Z","steps":["trace[2079812402] 'process raft request' (duration: 169.68743ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:25.026678Z","caller":"traceutil/trace.go:171","msg":"trace[2005636113] transaction","detail":"{read_only:false; response_revision:28193; number_of_response:1; }","duration":"166.719746ms","start":"2026-06-26T04:24:24.859948Z","end":"2026-06-26T04:24:25.026668Z","steps":["trace[2005636113] 'process raft request' (duration: 166.522801ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:25.02677Z","caller":"traceutil/trace.go:171","msg":"trace[1571588739] transaction","detail":"{read_only:false; response_revision:28192; number_of_response:1; }","duration":"169.667248ms","start":"2026-06-26T04:24:24.857091Z","end":"2026-06-26T04:24:25.026758Z","steps":["trace[1571588739] 'process raft request' (duration: 169.341672ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:25.181861Z","caller":"traceutil/trace.go:171","msg":"trace[1225115248] linearizableReadLoop","detail":"{readStateIndex:29481; appliedIndex:29480; }","duration":"150.405191ms","start":"2026-06-26T04:24:25.031422Z","end":"2026-06-26T04:24:25.181827Z","steps":["trace[1225115248] 'read index received' (duration: 137.048443ms)","trace[1225115248] 'applied index is now lower than readState.Index' (duration: 13.354848ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:25.181916Z","caller":"traceutil/trace.go:171","msg":"trace[814372725] transaction","detail":"{read_only:false; response_revision:28194; number_of_response:1; }","duration":"166.106382ms","start":"2026-06-26T04:24:25.015757Z","end":"2026-06-26T04:24:25.181863Z","steps":["trace[814372725] 'process raft request' (duration: 152.718603ms)","trace[814372725] 'compare' (duration: 12.95722ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:24:25.182244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.060224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11498"} {"level":"info","ts":"2026-06-26T04:24:25.182308Z","caller":"traceutil/trace.go:171","msg":"trace[2078459600] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28194; }","duration":"150.186178ms","start":"2026-06-26T04:24:25.032104Z","end":"2026-06-26T04:24:25.182291Z","steps":["trace[2078459600] 'agreement among raft nodes before linearized reading' (duration: 149.929371ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:25.183511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.687437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-26T04:24:25.183715Z","caller":"traceutil/trace.go:171","msg":"trace[1360530342] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28194; }","duration":"152.306183ms","start":"2026-06-26T04:24:25.031379Z","end":"2026-06-26T04:24:25.183685Z","steps":["trace[1360530342] 'agreement among raft nodes before linearized reading' (duration: 150.575955ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:25.604609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.818758ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:24:25.605082Z","caller":"traceutil/trace.go:171","msg":"trace[890922991] transaction","detail":"{read_only:false; response_revision:28195; number_of_response:1; }","duration":"415.066454ms","start":"2026-06-26T04:24:25.189704Z","end":"2026-06-26T04:24:25.60477Z","steps":["trace[890922991] 'process raft request' (duration: 283.95043ms)","trace[890922991] 'compare' (duration: 130.569963ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:24:25.605196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:24:25.189664Z","time spent":"415.478673ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:24:25.633306Z","caller":"traceutil/trace.go:171","msg":"trace[849444356] linearizableReadLoop","detail":"{readStateIndex:29483; appliedIndex:29482; }","duration":"124.863301ms","start":"2026-06-26T04:24:25.508415Z","end":"2026-06-26T04:24:25.633278Z","steps":["trace[849444356] 'read index received' (duration: 124.687946ms)","trace[849444356] 'applied index is now lower than readState.Index' (duration: 174.205µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:25.633403Z","caller":"traceutil/trace.go:171","msg":"trace[1179440128] transaction","detail":"{read_only:false; response_revision:28196; number_of_response:1; }","duration":"395.602845ms","start":"2026-06-26T04:24:25.237743Z","end":"2026-06-26T04:24:25.633346Z","steps":["trace[1179440128] 'process raft request' (duration: 395.375279ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:25.633612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:24:25.237694Z","time spent":"395.808279ms","remote":"127.0.0.1:38440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:24:25.633681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.243268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-06-26T04:24:25.633736Z","caller":"traceutil/trace.go:171","msg":"trace[1058514821] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:28196; }","duration":"125.349771ms","start":"2026-06-26T04:24:25.508371Z","end":"2026-06-26T04:24:25.633721Z","steps":["trace[1058514821] 'agreement among raft nodes before linearized reading' (duration: 125.001924ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:25.908598Z","caller":"traceutil/trace.go:171","msg":"trace[776411849] linearizableReadLoop","detail":"{readStateIndex:29484; appliedIndex:29483; }","duration":"253.732267ms","start":"2026-06-26T04:24:25.65484Z","end":"2026-06-26T04:24:25.908572Z","steps":["trace[776411849] 'read index received' (duration: 253.696796ms)","trace[776411849] 'applied index is now lower than readState.Index' (duration: 34.461µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:25.908709Z","caller":"traceutil/trace.go:171","msg":"trace[898453412] transaction","detail":"{read_only:false; number_of_response:0; response_revision:28196; }","duration":"267.679169ms","start":"2026-06-26T04:24:25.640977Z","end":"2026-06-26T04:24:25.908656Z","steps":["trace[898453412] 'process raft request' (duration: 267.503275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:25.90878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.91571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-26T04:24:25.908833Z","caller":"traceutil/trace.go:171","msg":"trace[1818818637] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:28196; }","duration":"254.022563ms","start":"2026-06-26T04:24:25.6548Z","end":"2026-06-26T04:24:25.908823Z","steps":["trace[1818818637] 'agreement among raft nodes before linearized reading' (duration: 253.89469ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:25.926246Z","caller":"traceutil/trace.go:171","msg":"trace[42981487] transaction","detail":"{read_only:false; response_revision:28199; number_of_response:1; }","duration":"228.715726ms","start":"2026-06-26T04:24:25.697511Z","end":"2026-06-26T04:24:25.926227Z","steps":["trace[42981487] 'process raft request' (duration: 228.618824ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:25.926289Z","caller":"traceutil/trace.go:171","msg":"trace[492821508] transaction","detail":"{read_only:false; response_revision:28198; number_of_response:1; }","duration":"230.078574ms","start":"2026-06-26T04:24:25.696161Z","end":"2026-06-26T04:24:25.92624Z","steps":["trace[492821508] 'process raft request' (duration: 229.934101ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:25.926324Z","caller":"traceutil/trace.go:171","msg":"trace[20516551] transaction","detail":"{read_only:false; response_revision:28197; number_of_response:1; }","duration":"244.20496ms","start":"2026-06-26T04:24:25.682086Z","end":"2026-06-26T04:24:25.926291Z","steps":["trace[20516551] 'process raft request' (duration: 243.852343ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:25.926588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.212808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-06-26T04:24:25.926672Z","caller":"traceutil/trace.go:171","msg":"trace[35450818] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:28199; }","duration":"144.361281ms","start":"2026-06-26T04:24:25.782288Z","end":"2026-06-26T04:24:25.92665Z","steps":["trace[35450818] 'agreement among raft nodes before linearized reading' (duration: 144.082415ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:26.228698Z","caller":"traceutil/trace.go:171","msg":"trace[419723107] linearizableReadLoop","detail":"{readStateIndex:29489; appliedIndex:29487; }","duration":"254.295229ms","start":"2026-06-26T04:24:25.974371Z","end":"2026-06-26T04:24:26.228666Z","steps":["trace[419723107] 'read index received' (duration: 93.484692ms)","trace[419723107] 'applied index is now lower than readState.Index' (duration: 160.809357ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:26.228898Z","caller":"traceutil/trace.go:171","msg":"trace[2008912500] transaction","detail":"{read_only:false; response_revision:28200; number_of_response:1; }","duration":"289.359036ms","start":"2026-06-26T04:24:25.939471Z","end":"2026-06-26T04:24:26.22883Z","steps":["trace[2008912500] 'process raft request' (duration: 288.937957ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:26.22908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.699378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-06-26T04:24:26.229215Z","caller":"traceutil/trace.go:171","msg":"trace[292444085] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28200; }","duration":"254.900422ms","start":"2026-06-26T04:24:25.974293Z","end":"2026-06-26T04:24:26.229194Z","steps":["trace[292444085] 'agreement among raft nodes before linearized reading' (duration: 254.524874ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:26.241397Z","caller":"traceutil/trace.go:171","msg":"trace[1520415499] transaction","detail":"{read_only:false; response_revision:28201; number_of_response:1; }","duration":"167.466421ms","start":"2026-06-26T04:24:26.073897Z","end":"2026-06-26T04:24:26.241364Z","steps":["trace[1520415499] 'process raft request' (duration: 167.043101ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:26.390972Z","caller":"traceutil/trace.go:171","msg":"trace[358549731] linearizableReadLoop","detail":"{readStateIndex:29491; appliedIndex:29490; }","duration":"147.880438ms","start":"2026-06-26T04:24:26.243066Z","end":"2026-06-26T04:24:26.390946Z","steps":["trace[358549731] 'read index received' (duration: 147.311986ms)","trace[358549731] 'applied index is now lower than readState.Index' (duration: 567.392µs)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:24:26.3912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.113042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-06-26T04:24:26.391285Z","caller":"traceutil/trace.go:171","msg":"trace[1019426197] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:28202; }","duration":"148.265267ms","start":"2026-06-26T04:24:26.243003Z","end":"2026-06-26T04:24:26.391268Z","steps":["trace[1019426197] 'agreement among raft nodes before linearized reading' (duration: 148.058642ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:26.391187Z","caller":"traceutil/trace.go:171","msg":"trace[2043493257] transaction","detail":"{read_only:false; response_revision:28202; number_of_response:1; }","duration":"154.95378ms","start":"2026-06-26T04:24:26.23606Z","end":"2026-06-26T04:24:26.391014Z","steps":["trace[2043493257] 'process raft request' (duration: 154.682944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:26.391504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.510305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11498"} {"level":"info","ts":"2026-06-26T04:24:26.391567Z","caller":"traceutil/trace.go:171","msg":"trace[1323315030] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28202; }","duration":"132.633978ms","start":"2026-06-26T04:24:26.258917Z","end":"2026-06-26T04:24:26.391551Z","steps":["trace[1323315030] 'agreement among raft nodes before linearized reading' (duration: 132.180758ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:37.328701Z","caller":"traceutil/trace.go:171","msg":"trace[1946936102] transaction","detail":"{read_only:false; response_revision:28357; number_of_response:1; }","duration":"106.854531ms","start":"2026-06-26T04:24:37.22182Z","end":"2026-06-26T04:24:37.328675Z","steps":["trace[1946936102] 'process raft request' (duration: 106.685627ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:37.841655Z","caller":"traceutil/trace.go:171","msg":"trace[369183872] transaction","detail":"{read_only:false; response_revision:28358; number_of_response:1; }","duration":"112.601926ms","start":"2026-06-26T04:24:37.729017Z","end":"2026-06-26T04:24:37.841619Z","steps":["trace[369183872] 'process raft request' (duration: 112.36151ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:38.267611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.610559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:24:38.267886Z","caller":"traceutil/trace.go:171","msg":"trace[273010814] transaction","detail":"{read_only:false; response_revision:28363; number_of_response:1; }","duration":"291.154525ms","start":"2026-06-26T04:24:37.976714Z","end":"2026-06-26T04:24:38.267868Z","steps":["trace[273010814] 'process raft request' (duration: 291.107974ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:38.268245Z","caller":"traceutil/trace.go:171","msg":"trace[378677080] transaction","detail":"{read_only:false; response_revision:28361; number_of_response:1; }","duration":"302.524991ms","start":"2026-06-26T04:24:37.96571Z","end":"2026-06-26T04:24:38.268235Z","steps":["trace[378677080] 'process raft request' (duration: 301.9974ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:38.268297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:24:37.965681Z","time spent":"302.589173ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:24:38.268345Z","caller":"traceutil/trace.go:171","msg":"trace[30969933] transaction","detail":"{read_only:false; response_revision:28360; number_of_response:1; }","duration":"417.097639ms","start":"2026-06-26T04:24:37.851222Z","end":"2026-06-26T04:24:38.268319Z","steps":["trace[30969933] 'process raft request' (duration: 159.675073ms)","trace[30969933] 'compare' (duration: 256.479606ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:38.268354Z","caller":"traceutil/trace.go:171","msg":"trace[2078227487] linearizableReadLoop","detail":"{readStateIndex:29653; appliedIndex:29652; }","duration":"347.009053ms","start":"2026-06-26T04:24:37.921316Z","end":"2026-06-26T04:24:38.268325Z","steps":["trace[2078227487] 'read index received' (duration: 89.596247ms)","trace[2078227487] 'applied index is now lower than readState.Index' (duration: 257.409846ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:24:38.268477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:24:37.8512Z","time spent":"417.200271ms","remote":"127.0.0.1:38180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3743,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:24:38.268519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.188648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:24:38.268549Z","caller":"traceutil/trace.go:171","msg":"trace[983307552] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:28363; }","duration":"347.242239ms","start":"2026-06-26T04:24:37.921298Z","end":"2026-06-26T04:24:38.26854Z","steps":["trace[983307552] 'agreement among raft nodes before linearized reading' (duration: 347.142987ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:38.268576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:24:37.92123Z","time spent":"347.33763ms","remote":"127.0.0.1:41120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":28,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"info","ts":"2026-06-26T04:24:38.268476Z","caller":"traceutil/trace.go:171","msg":"trace[1330041802] transaction","detail":"{read_only:false; response_revision:28362; number_of_response:1; }","duration":"297.739557ms","start":"2026-06-26T04:24:37.97069Z","end":"2026-06-26T04:24:38.26843Z","steps":["trace[1330041802] 'process raft request' (duration: 297.084693ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:38.273493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.369529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-06-26T04:24:38.273533Z","caller":"traceutil/trace.go:171","msg":"trace[49277487] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:28365; }","duration":"259.44452ms","start":"2026-06-26T04:24:38.014078Z","end":"2026-06-26T04:24:38.273523Z","steps":["trace[49277487] 'agreement among raft nodes before linearized reading' (duration: 259.337058ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:38.273671Z","caller":"traceutil/trace.go:171","msg":"trace[1569491937] transaction","detail":"{read_only:false; response_revision:28364; number_of_response:1; }","duration":"250.452305ms","start":"2026-06-26T04:24:38.023213Z","end":"2026-06-26T04:24:38.273665Z","steps":["trace[1569491937] 'process raft request' (duration: 250.105118ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:38.273953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.361879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3191"} {"level":"info","ts":"2026-06-26T04:24:38.273969Z","caller":"traceutil/trace.go:171","msg":"trace[1612466053] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:28365; }","duration":"137.412541ms","start":"2026-06-26T04:24:38.136551Z","end":"2026-06-26T04:24:38.273964Z","steps":["trace[1612466053] 'agreement among raft nodes before linearized reading' (duration: 137.35732ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:38.681267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.839035ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:24:38.68138Z","caller":"traceutil/trace.go:171","msg":"trace[1853618873] linearizableReadLoop","detail":"{readStateIndex:29659; appliedIndex:29658; }","duration":"395.876099ms","start":"2026-06-26T04:24:38.285485Z","end":"2026-06-26T04:24:38.681361Z","steps":["trace[1853618873] 'read index received' (duration: 137.82767ms)","trace[1853618873] 'applied index is now lower than readState.Index' (duration: 258.046779ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:38.681452Z","caller":"traceutil/trace.go:171","msg":"trace[1673917858] transaction","detail":"{read_only:false; response_revision:28366; number_of_response:1; }","duration":"396.803609ms","start":"2026-06-26T04:24:38.284637Z","end":"2026-06-26T04:24:38.68144Z","steps":["trace[1673917858] 'process raft request' (duration: 138.698478ms)","trace[1673917858] 'compare' (duration: 257.503298ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:24:38.681511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:24:38.284625Z","time spent":"396.85224ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:24:38.681843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.350079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9726"} {"level":"info","ts":"2026-06-26T04:24:38.681875Z","caller":"traceutil/trace.go:171","msg":"trace[997850366] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:28366; }","duration":"396.39506ms","start":"2026-06-26T04:24:38.285471Z","end":"2026-06-26T04:24:38.681866Z","steps":["trace[997850366] 'agreement among raft nodes before linearized reading' (duration: 396.252407ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:38.681911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:24:38.285466Z","time spent":"396.433161ms","remote":"127.0.0.1:38440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":9748,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" "} {"level":"warn","ts":"2026-06-26T04:24:38.686013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.125732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18bc86e6492287df\" ","response":"range_response_count:1 size:690"} {"level":"info","ts":"2026-06-26T04:24:38.686163Z","caller":"traceutil/trace.go:171","msg":"trace[109908375] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18bc86e6492287df; range_end:; response_count:1; response_revision:28367; }","duration":"400.328326ms","start":"2026-06-26T04:24:38.285821Z","end":"2026-06-26T04:24:38.686149Z","steps":["trace[109908375] 'agreement among raft nodes before linearized reading' (duration: 399.942778ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:38.686276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:24:38.285793Z","time spent":"400.470259ms","remote":"127.0.0.1:38092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":712,"request content":"key:\"/registry/events/openstack/rabbitmq-manila.18bc86e6492287df\" "} {"level":"warn","ts":"2026-06-26T04:24:38.686418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.463521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-06-26T04:24:38.686467Z","caller":"traceutil/trace.go:171","msg":"trace[122918689] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28367; }","duration":"241.550893ms","start":"2026-06-26T04:24:38.444904Z","end":"2026-06-26T04:24:38.686455Z","steps":["trace[122918689] 'agreement among raft nodes before linearized reading' (duration: 241.089813ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:38.984196Z","caller":"traceutil/trace.go:171","msg":"trace[201049365] linearizableReadLoop","detail":"{readStateIndex:29662; appliedIndex:29660; }","duration":"287.187959ms","start":"2026-06-26T04:24:38.696993Z","end":"2026-06-26T04:24:38.984181Z","steps":["trace[201049365] 'read index received' (duration: 197.246654ms)","trace[201049365] 'applied index is now lower than readState.Index' (duration: 89.940555ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:38.984464Z","caller":"traceutil/trace.go:171","msg":"trace[600431885] transaction","detail":"{read_only:false; response_revision:28368; number_of_response:1; }","duration":"294.629721ms","start":"2026-06-26T04:24:38.689826Z","end":"2026-06-26T04:24:38.984456Z","steps":["trace[600431885] 'process raft request' (duration: 204.4096ms)","trace[600431885] 'compare' (duration: 89.793822ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:38.984728Z","caller":"traceutil/trace.go:171","msg":"trace[1931677785] transaction","detail":"{read_only:false; response_revision:28369; number_of_response:1; }","duration":"293.393235ms","start":"2026-06-26T04:24:38.691329Z","end":"2026-06-26T04:24:38.984723Z","steps":["trace[1931677785] 'process raft request' (duration: 292.811261ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:38.984925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.917115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9726"} {"level":"info","ts":"2026-06-26T04:24:38.985248Z","caller":"traceutil/trace.go:171","msg":"trace[1740687098] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:28369; }","duration":"288.277164ms","start":"2026-06-26T04:24:38.696964Z","end":"2026-06-26T04:24:38.985241Z","steps":["trace[1740687098] 'agreement among raft nodes before linearized reading' (duration: 287.858705ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:38.984991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.809042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-06-26T04:24:38.985436Z","caller":"traceutil/trace.go:171","msg":"trace[1805639144] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28369; }","duration":"164.283272ms","start":"2026-06-26T04:24:38.821143Z","end":"2026-06-26T04:24:38.985427Z","steps":["trace[1805639144] 'agreement among raft nodes before linearized reading' (duration: 163.808672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:38.985058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.423821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-26T04:24:38.985631Z","caller":"traceutil/trace.go:171","msg":"trace[2098754709] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28369; }","duration":"261.056515ms","start":"2026-06-26T04:24:38.724567Z","end":"2026-06-26T04:24:38.985624Z","steps":["trace[2098754709] 'agreement among raft nodes before linearized reading' (duration: 260.342309ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:39.279923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.398086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:24:39.280095Z","caller":"traceutil/trace.go:171","msg":"trace[1191925616] linearizableReadLoop","detail":"{readStateIndex:29664; appliedIndex:29662; }","duration":"286.786741ms","start":"2026-06-26T04:24:38.993294Z","end":"2026-06-26T04:24:39.28008Z","steps":["trace[1191925616] 'read index received' (duration: 121.124969ms)","trace[1191925616] 'applied index is now lower than readState.Index' (duration: 165.660431ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:39.280273Z","caller":"traceutil/trace.go:171","msg":"trace[997090713] transaction","detail":"{read_only:false; response_revision:28370; number_of_response:1; }","duration":"289.873257ms","start":"2026-06-26T04:24:38.99039Z","end":"2026-06-26T04:24:39.280264Z","steps":["trace[997090713] 'process raft request' (duration: 124.057012ms)","trace[997090713] 'compare' (duration: 165.231243ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:39.280403Z","caller":"traceutil/trace.go:171","msg":"trace[208076492] transaction","detail":"{read_only:false; response_revision:28371; number_of_response:1; }","duration":"288.369495ms","start":"2026-06-26T04:24:38.992021Z","end":"2026-06-26T04:24:39.28039Z","steps":["trace[208076492] 'process raft request' (duration: 287.989397ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:39.280579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.339463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-06-26T04:24:39.2806Z","caller":"traceutil/trace.go:171","msg":"trace[561353409] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28371; }","duration":"287.391823ms","start":"2026-06-26T04:24:38.993201Z","end":"2026-06-26T04:24:39.280592Z","steps":["trace[561353409] 'agreement among raft nodes before linearized reading' (duration: 287.301941ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:39.280856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.296035ms","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-06-26T04:24:39.280874Z","caller":"traceutil/trace.go:171","msg":"trace[878009536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28371; }","duration":"184.354986ms","start":"2026-06-26T04:24:39.096514Z","end":"2026-06-26T04:24:39.280869Z","steps":["trace[878009536] 'agreement among raft nodes before linearized reading' (duration: 184.297495ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:39.280983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.540061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-26T04:24:39.281027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.351834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-06-26T04:24:39.28102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.723115ms","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-06-26T04:24:39.281048Z","caller":"traceutil/trace.go:171","msg":"trace[945877328] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28371; }","duration":"183.380335ms","start":"2026-06-26T04:24:39.097662Z","end":"2026-06-26T04:24:39.281042Z","steps":["trace[945877328] 'agreement among raft nodes before linearized reading' (duration: 183.321194ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:39.281049Z","caller":"traceutil/trace.go:171","msg":"trace[531395386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28371; }","duration":"185.625614ms","start":"2026-06-26T04:24:39.095406Z","end":"2026-06-26T04:24:39.281032Z","steps":["trace[531395386] 'agreement among raft nodes before linearized reading' (duration: 185.481511ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:39.281075Z","caller":"traceutil/trace.go:171","msg":"trace[1693419299] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28371; }","duration":"191.813947ms","start":"2026-06-26T04:24:39.089242Z","end":"2026-06-26T04:24:39.281056Z","steps":["trace[1693419299] 'agreement among raft nodes before linearized reading' (duration: 191.677384ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:39.529947Z","caller":"traceutil/trace.go:171","msg":"trace[256445931] transaction","detail":"{read_only:false; response_revision:28373; number_of_response:1; }","duration":"241.343568ms","start":"2026-06-26T04:24:39.288579Z","end":"2026-06-26T04:24:39.529923Z","steps":["trace[256445931] 'process raft request' (duration: 241.018391ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:39.530033Z","caller":"traceutil/trace.go:171","msg":"trace[1230988062] transaction","detail":"{read_only:false; response_revision:28374; number_of_response:1; }","duration":"241.234496ms","start":"2026-06-26T04:24:39.288758Z","end":"2026-06-26T04:24:39.529992Z","steps":["trace[1230988062] 'process raft request' (duration: 241.007891ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:39.530245Z","caller":"traceutil/trace.go:171","msg":"trace[1630625823] transaction","detail":"{read_only:false; response_revision:28376; number_of_response:1; }","duration":"238.249291ms","start":"2026-06-26T04:24:39.291988Z","end":"2026-06-26T04:24:39.530238Z","steps":["trace[1630625823] 'process raft request' (duration: 238.145869ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:39.530256Z","caller":"traceutil/trace.go:171","msg":"trace[608950930] transaction","detail":"{read_only:false; response_revision:28375; number_of_response:1; }","duration":"239.790704ms","start":"2026-06-26T04:24:39.29045Z","end":"2026-06-26T04:24:39.530241Z","steps":["trace[608950930] 'process raft request' (duration: 239.472387ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:39.539648Z","caller":"traceutil/trace.go:171","msg":"trace[1788823026] transaction","detail":"{read_only:false; response_revision:28377; number_of_response:1; }","duration":"206.169049ms","start":"2026-06-26T04:24:39.333444Z","end":"2026-06-26T04:24:39.539613Z","steps":["trace[1788823026] 'process raft request' (duration: 205.950404ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:40.150529Z","caller":"traceutil/trace.go:171","msg":"trace[1447278299] transaction","detail":"{read_only:false; response_revision:28379; number_of_response:1; }","duration":"235.182685ms","start":"2026-06-26T04:24:39.915311Z","end":"2026-06-26T04:24:40.150493Z","steps":["trace[1447278299] 'process raft request' (duration: 235.072963ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:42.320468Z","caller":"traceutil/trace.go:171","msg":"trace[1872037612] transaction","detail":"{read_only:false; response_revision:28399; number_of_response:1; }","duration":"149.663076ms","start":"2026-06-26T04:24:42.170765Z","end":"2026-06-26T04:24:42.320428Z","steps":["trace[1872037612] 'process raft request' (duration: 149.444761ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:42.505018Z","caller":"traceutil/trace.go:171","msg":"trace[342046249] transaction","detail":"{read_only:false; response_revision:28401; number_of_response:1; }","duration":"222.502831ms","start":"2026-06-26T04:24:42.282492Z","end":"2026-06-26T04:24:42.504995Z","steps":["trace[342046249] 'process raft request' (duration: 222.420599ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:42.505146Z","caller":"traceutil/trace.go:171","msg":"trace[993797367] linearizableReadLoop","detail":"{readStateIndex:29697; appliedIndex:29694; }","duration":"184.731815ms","start":"2026-06-26T04:24:42.320392Z","end":"2026-06-26T04:24:42.505124Z","steps":["trace[993797367] 'read index received' (duration: 184.253844ms)","trace[993797367] 'applied index is now lower than readState.Index' (duration: 477.061µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:42.505284Z","caller":"traceutil/trace.go:171","msg":"trace[1842137590] transaction","detail":"{read_only:false; response_revision:28400; number_of_response:1; }","duration":"222.827898ms","start":"2026-06-26T04:24:42.282435Z","end":"2026-06-26T04:24:42.505263Z","steps":["trace[1842137590] 'process raft request' (duration: 222.310867ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:42.505334Z","caller":"traceutil/trace.go:171","msg":"trace[1450295289] transaction","detail":"{read_only:false; response_revision:28402; number_of_response:1; }","duration":"222.02302ms","start":"2026-06-26T04:24:42.283303Z","end":"2026-06-26T04:24:42.505326Z","steps":["trace[1450295289] 'process raft request' (duration: 221.653112ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:42.505559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.262403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-821638fa-54d5-42b7-a19f-c838634da7a9\" ","response":"range_response_count:1 size:3947"} {"level":"info","ts":"2026-06-26T04:24:42.505595Z","caller":"traceutil/trace.go:171","msg":"trace[2082030766] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-821638fa-54d5-42b7-a19f-c838634da7a9; range_end:; response_count:1; response_revision:28402; }","duration":"202.370315ms","start":"2026-06-26T04:24:42.303214Z","end":"2026-06-26T04:24:42.505584Z","steps":["trace[2082030766] 'agreement among raft nodes before linearized reading' (duration: 202.227992ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:42.50582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.175977ms","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-06-26T04:24:42.50585Z","caller":"traceutil/trace.go:171","msg":"trace[1710585030] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28402; }","duration":"181.269169ms","start":"2026-06-26T04:24:42.32457Z","end":"2026-06-26T04:24:42.505839Z","steps":["trace[1710585030] 'agreement among raft nodes before linearized reading' (duration: 181.191367ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:42.506044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.082386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:24:42.506072Z","caller":"traceutil/trace.go:171","msg":"trace[1129400509] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:28402; }","duration":"144.156058ms","start":"2026-06-26T04:24:42.361907Z","end":"2026-06-26T04:24:42.506063Z","steps":["trace[1129400509] 'agreement among raft nodes before linearized reading' (duration: 144.102157ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:45.87309Z","caller":"traceutil/trace.go:171","msg":"trace[1204057438] linearizableReadLoop","detail":"{readStateIndex:29737; appliedIndex:29736; }","duration":"312.103188ms","start":"2026-06-26T04:24:45.560951Z","end":"2026-06-26T04:24:45.873054Z","steps":["trace[1204057438] 'read index received' (duration: 311.895814ms)","trace[1204057438] 'applied index is now lower than readState.Index' (duration: 205.794µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:45.873165Z","caller":"traceutil/trace.go:171","msg":"trace[781743664] transaction","detail":"{read_only:false; response_revision:28441; number_of_response:1; }","duration":"312.476547ms","start":"2026-06-26T04:24:45.560644Z","end":"2026-06-26T04:24:45.873121Z","steps":["trace[781743664] 'process raft request' (duration: 312.160749ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:45.873355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:24:45.560628Z","time spent":"312.624021ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:24:45.873348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.374465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-26T04:24:45.873478Z","caller":"traceutil/trace.go:171","msg":"trace[743101315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28441; }","duration":"312.545849ms","start":"2026-06-26T04:24:45.560907Z","end":"2026-06-26T04:24:45.873453Z","steps":["trace[743101315] 'agreement among raft nodes before linearized reading' (duration: 312.302194ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:45.873595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:24:45.560894Z","time spent":"312.641441ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-26T04:24:45.912121Z","caller":"traceutil/trace.go:171","msg":"trace[1035157492] transaction","detail":"{read_only:false; response_revision:28443; number_of_response:1; }","duration":"130.49865ms","start":"2026-06-26T04:24:45.781601Z","end":"2026-06-26T04:24:45.912099Z","steps":["trace[1035157492] 'process raft request' (duration: 130.45823ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:45.91219Z","caller":"traceutil/trace.go:171","msg":"trace[1569647337] transaction","detail":"{read_only:false; response_revision:28442; number_of_response:1; }","duration":"347.247378ms","start":"2026-06-26T04:24:45.564925Z","end":"2026-06-26T04:24:45.912172Z","steps":["trace[1569647337] 'process raft request' (duration: 347.033623ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:45.912297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:24:45.564898Z","time spent":"347.33407ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:24:45.912326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.775425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-26T04:24:45.912392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.543686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:24:45.912488Z","caller":"traceutil/trace.go:171","msg":"trace[1036326167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28443; }","duration":"298.695789ms","start":"2026-06-26T04:24:45.613772Z","end":"2026-06-26T04:24:45.912468Z","steps":["trace[1036326167] 'agreement among raft nodes before linearized reading' (duration: 298.497504ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:45.912467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.355169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-06-26T04:24:45.912417Z","caller":"traceutil/trace.go:171","msg":"trace[169590213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28443; }","duration":"350.890358ms","start":"2026-06-26T04:24:45.561506Z","end":"2026-06-26T04:24:45.912396Z","steps":["trace[169590213] 'agreement among raft nodes before linearized reading' (duration: 350.610211ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:45.91257Z","caller":"traceutil/trace.go:171","msg":"trace[1815080507] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28443; }","duration":"241.532583ms","start":"2026-06-26T04:24:45.671015Z","end":"2026-06-26T04:24:45.912548Z","steps":["trace[1815080507] 'agreement among raft nodes before linearized reading' (duration: 241.191105ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:45.91259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:24:45.561496Z","time spent":"351.080792ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-26T04:24:45.912707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.803384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-06-26T04:24:45.912802Z","caller":"traceutil/trace.go:171","msg":"trace[1817427037] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:28443; }","duration":"128.946128ms","start":"2026-06-26T04:24:45.783834Z","end":"2026-06-26T04:24:45.91278Z","steps":["trace[1817427037] 'agreement among raft nodes before linearized reading' (duration: 128.55817ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:46.07636Z","caller":"traceutil/trace.go:171","msg":"trace[1971733812] transaction","detail":"{read_only:false; response_revision:28446; number_of_response:1; }","duration":"146.718272ms","start":"2026-06-26T04:24:45.929601Z","end":"2026-06-26T04:24:46.076319Z","steps":["trace[1971733812] 'process raft request' (duration: 51.108735ms)","trace[1971733812] 'compare' (duration: 95.426463ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:46.089853Z","caller":"traceutil/trace.go:171","msg":"trace[1203366490] linearizableReadLoop","detail":"{readStateIndex:29744; appliedIndex:29742; }","duration":"112.478392ms","start":"2026-06-26T04:24:45.977333Z","end":"2026-06-26T04:24:46.089812Z","steps":["trace[1203366490] 'read index received' (duration: 3.316521ms)","trace[1203366490] 'applied index is now lower than readState.Index' (duration: 109.159901ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:46.089958Z","caller":"traceutil/trace.go:171","msg":"trace[429168300] transaction","detail":"{read_only:false; response_revision:28447; number_of_response:1; }","duration":"144.554576ms","start":"2026-06-26T04:24:45.945346Z","end":"2026-06-26T04:24:46.089901Z","steps":["trace[429168300] 'process raft request' (duration: 144.225759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:46.090978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.612536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3191"} {"level":"info","ts":"2026-06-26T04:24:46.091075Z","caller":"traceutil/trace.go:171","msg":"trace[1943723281] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:28447; }","duration":"113.747249ms","start":"2026-06-26T04:24:45.977305Z","end":"2026-06-26T04:24:46.091052Z","steps":["trace[1943723281] 'agreement among raft nodes before linearized reading' (duration: 113.483114ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:24:46.391204Z","caller":"traceutil/trace.go:171","msg":"trace[392341769] transaction","detail":"{read_only:false; response_revision:28448; number_of_response:1; }","duration":"291.000341ms","start":"2026-06-26T04:24:46.100166Z","end":"2026-06-26T04:24:46.391166Z","steps":["trace[392341769] 'process raft request' (duration: 264.288114ms)","trace[392341769] 'compare' (duration: 26.575654ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:24:46.418991Z","caller":"traceutil/trace.go:171","msg":"trace[1184181901] transaction","detail":"{read_only:false; response_revision:28449; number_of_response:1; }","duration":"315.236966ms","start":"2026-06-26T04:24:46.103708Z","end":"2026-06-26T04:24:46.418945Z","steps":["trace[1184181901] 'process raft request' (duration: 314.776646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:24:46.419732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:24:46.103602Z","time spent":"315.541442ms","remote":"127.0.0.1:38180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10758,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:25:04.245527Z","caller":"traceutil/trace.go:171","msg":"trace[761024634] transaction","detail":"{read_only:false; response_revision:28643; number_of_response:1; }","duration":"227.992141ms","start":"2026-06-26T04:25:04.017508Z","end":"2026-06-26T04:25:04.2455Z","steps":["trace[761024634] 'process raft request' (duration: 227.828347ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:04.254112Z","caller":"traceutil/trace.go:171","msg":"trace[1724783569] transaction","detail":"{read_only:false; response_revision:28644; number_of_response:1; }","duration":"236.488343ms","start":"2026-06-26T04:25:04.017596Z","end":"2026-06-26T04:25:04.254084Z","steps":["trace[1724783569] 'process raft request' (duration: 236.318379ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:04.25434Z","caller":"traceutil/trace.go:171","msg":"trace[775913885] transaction","detail":"{read_only:false; response_revision:28645; number_of_response:1; }","duration":"236.611716ms","start":"2026-06-26T04:25:04.017706Z","end":"2026-06-26T04:25:04.254318Z","steps":["trace[775913885] 'process raft request' (duration: 236.33503ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:04.254322Z","caller":"traceutil/trace.go:171","msg":"trace[1690669805] linearizableReadLoop","detail":"{readStateIndex:29949; appliedIndex:29946; }","duration":"163.429363ms","start":"2026-06-26T04:25:04.090821Z","end":"2026-06-26T04:25:04.25425Z","steps":["trace[1690669805] 'read index received' (duration: 154.545392ms)","trace[1690669805] 'applied index is now lower than readState.Index' (duration: 8.881441ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:25:04.254504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.658608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-26T04:25:04.254588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.726018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-26T04:25:04.254584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.879323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:25:04.254625Z","caller":"traceutil/trace.go:171","msg":"trace[1416036932] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:28645; }","duration":"125.80224ms","start":"2026-06-26T04:25:04.128813Z","end":"2026-06-26T04:25:04.254615Z","steps":["trace[1416036932] 'agreement among raft nodes before linearized reading' (duration: 125.741699ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:04.254604Z","caller":"traceutil/trace.go:171","msg":"trace[331704147] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:28645; }","duration":"163.811091ms","start":"2026-06-26T04:25:04.090773Z","end":"2026-06-26T04:25:04.254584Z","steps":["trace[331704147] 'agreement among raft nodes before linearized reading' (duration: 163.658458ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:04.254646Z","caller":"traceutil/trace.go:171","msg":"trace[242619442] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:28645; }","duration":"127.034386ms","start":"2026-06-26T04:25:04.127593Z","end":"2026-06-26T04:25:04.254627Z","steps":["trace[242619442] 'agreement among raft nodes before linearized reading' (duration: 126.869923ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:04.254518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.265986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:25:04.254834Z","caller":"traceutil/trace.go:171","msg":"trace[565990649] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:28645; }","duration":"111.642105ms","start":"2026-06-26T04:25:04.143183Z","end":"2026-06-26T04:25:04.254825Z","steps":["trace[565990649] 'agreement among raft nodes before linearized reading' (duration: 111.282777ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:04.74148Z","caller":"traceutil/trace.go:171","msg":"trace[274189042] linearizableReadLoop","detail":"{readStateIndex:29950; appliedIndex:29949; }","duration":"226.106989ms","start":"2026-06-26T04:25:04.515332Z","end":"2026-06-26T04:25:04.741439Z","steps":["trace[274189042] 'read index received' (duration: 225.670609ms)","trace[274189042] 'applied index is now lower than readState.Index' (duration: 434.89µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:25:04.741601Z","caller":"traceutil/trace.go:171","msg":"trace[1571775343] transaction","detail":"{read_only:false; response_revision:28646; number_of_response:1; }","duration":"244.924185ms","start":"2026-06-26T04:25:04.496631Z","end":"2026-06-26T04:25:04.741555Z","steps":["trace[1571775343] 'process raft request' (duration: 244.443135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:04.741796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.443867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-06-26T04:25:04.74185Z","caller":"traceutil/trace.go:171","msg":"trace[1765337104] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:28646; }","duration":"227.228563ms","start":"2026-06-26T04:25:04.514602Z","end":"2026-06-26T04:25:04.741831Z","steps":["trace[1765337104] 'agreement among raft nodes before linearized reading' (duration: 227.021029ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:04.851929Z","caller":"traceutil/trace.go:171","msg":"trace[23250704] transaction","detail":"{read_only:false; response_revision:28647; number_of_response:1; }","duration":"263.62396ms","start":"2026-06-26T04:25:04.588252Z","end":"2026-06-26T04:25:04.851876Z","steps":["trace[23250704] 'process raft request' (duration: 262.19676ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:04.852273Z","caller":"traceutil/trace.go:171","msg":"trace[400377023] transaction","detail":"{read_only:false; response_revision:28648; number_of_response:1; }","duration":"258.373627ms","start":"2026-06-26T04:25:04.593854Z","end":"2026-06-26T04:25:04.852227Z","steps":["trace[400377023] 'process raft request' (duration: 257.972948ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:04.852307Z","caller":"traceutil/trace.go:171","msg":"trace[474060978] linearizableReadLoop","detail":"{readStateIndex:29953; appliedIndex:29950; }","duration":"110.690843ms","start":"2026-06-26T04:25:04.741583Z","end":"2026-06-26T04:25:04.852274Z","steps":["trace[474060978] 'read index received' (duration: 109.010687ms)","trace[474060978] 'applied index is now lower than readState.Index' (duration: 1.678786ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:25:04.852441Z","caller":"traceutil/trace.go:171","msg":"trace[1783078955] transaction","detail":"{read_only:false; response_revision:28649; number_of_response:1; }","duration":"258.409138ms","start":"2026-06-26T04:25:04.594019Z","end":"2026-06-26T04:25:04.852428Z","steps":["trace[1783078955] 'process raft request' (duration: 258.07543ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:04.85262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.709288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-06-26T04:25:04.852672Z","caller":"traceutil/trace.go:171","msg":"trace[1527982843] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28649; }","duration":"279.825241ms","start":"2026-06-26T04:25:04.572829Z","end":"2026-06-26T04:25:04.852654Z","steps":["trace[1527982843] 'agreement among raft nodes before linearized reading' (duration: 279.640807ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:05.145203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.603152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:25:05.145586Z","caller":"traceutil/trace.go:171","msg":"trace[745940264] transaction","detail":"{read_only:false; response_revision:28650; number_of_response:1; }","duration":"393.958287ms","start":"2026-06-26T04:25:04.751591Z","end":"2026-06-26T04:25:05.145549Z","steps":["trace[745940264] 'process raft request' (duration: 239.902476ms)","trace[745940264] 'compare' (duration: 153.116291ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:25:05.14576Z","caller":"traceutil/trace.go:171","msg":"trace[1419699904] transaction","detail":"{read_only:false; response_revision:28651; number_of_response:1; }","duration":"394.06775ms","start":"2026-06-26T04:25:04.751634Z","end":"2026-06-26T04:25:05.145702Z","steps":["trace[1419699904] 'process raft request' (duration: 393.679981ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:05.145794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:04.751576Z","time spent":"394.114331ms","remote":"127.0.0.1:38176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:25:05.145974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:04.751615Z","time spent":"394.255824ms","remote":"127.0.0.1:38262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:25:05.146286Z","caller":"traceutil/trace.go:171","msg":"trace[2022942176] transaction","detail":"{read_only:false; response_revision:28654; number_of_response:1; }","duration":"318.779682ms","start":"2026-06-26T04:25:04.827491Z","end":"2026-06-26T04:25:05.14627Z","steps":["trace[2022942176] 'process raft request' (duration: 318.596568ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:05.146281Z","caller":"traceutil/trace.go:171","msg":"trace[938206233] transaction","detail":"{read_only:false; response_revision:28653; number_of_response:1; }","duration":"385.392952ms","start":"2026-06-26T04:25:04.76085Z","end":"2026-06-26T04:25:05.146243Z","steps":["trace[938206233] 'process raft request' (duration: 385.106956ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:05.146373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:04.827443Z","time spent":"318.890344ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:25:05.146381Z","caller":"traceutil/trace.go:171","msg":"trace[2143732017] transaction","detail":"{read_only:false; response_revision:28652; number_of_response:1; }","duration":"394.123271ms","start":"2026-06-26T04:25:04.752215Z","end":"2026-06-26T04:25:05.146338Z","steps":["trace[2143732017] 'process raft request' (duration: 393.660101ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:05.146458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.659279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"warn","ts":"2026-06-26T04:25:05.146476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:04.76084Z","time spent":"385.531066ms","remote":"127.0.0.1:38440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9754,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:25:05.146515Z","caller":"traceutil/trace.go:171","msg":"trace[76027212] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28654; }","duration":"398.769252ms","start":"2026-06-26T04:25:04.747733Z","end":"2026-06-26T04:25:05.146502Z","steps":["trace[76027212] 'agreement among raft nodes before linearized reading' (duration: 398.558767ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:05.14656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:04.74771Z","time spent":"398.841145ms","remote":"127.0.0.1:38114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":669,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-26T04:25:05.146556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:04.752203Z","time spent":"394.242634ms","remote":"127.0.0.1:38262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:25:05.146861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.711424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-821638fa-54d5-42b7-a19f-c838634da7a9\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-06-26T04:25:05.146931Z","caller":"traceutil/trace.go:171","msg":"trace[1109639762] range","detail":"{range_begin:/registry/persistentvolumes/pvc-821638fa-54d5-42b7-a19f-c838634da7a9; range_end:; response_count:1; response_revision:28654; }","duration":"400.793726ms","start":"2026-06-26T04:25:04.746116Z","end":"2026-06-26T04:25:05.14691Z","steps":["trace[1109639762] 'agreement among raft nodes before linearized reading' (duration: 400.576422ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:05.146964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:04.746097Z","time spent":"400.859107ms","remote":"127.0.0.1:38164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1344,"request content":"key:\"/registry/persistentvolumes/pvc-821638fa-54d5-42b7-a19f-c838634da7a9\" "} {"level":"info","ts":"2026-06-26T04:25:05.146188Z","caller":"traceutil/trace.go:171","msg":"trace[193499794] linearizableReadLoop","detail":"{readStateIndex:29958; appliedIndex:29953; }","duration":"293.776842ms","start":"2026-06-26T04:25:04.852392Z","end":"2026-06-26T04:25:05.146169Z","steps":["trace[193499794] 'read index received' (duration: 139.094247ms)","trace[193499794] 'applied index is now lower than readState.Index' (duration: 154.681655ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:25:05.147174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.599935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-06-26T04:25:05.147229Z","caller":"traceutil/trace.go:171","msg":"trace[772968762] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28654; }","duration":"291.690117ms","start":"2026-06-26T04:25:04.85552Z","end":"2026-06-26T04:25:05.14721Z","steps":["trace[772968762] 'agreement among raft nodes before linearized reading' (duration: 291.563684ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:05.346195Z","caller":"traceutil/trace.go:171","msg":"trace[1154572375] transaction","detail":"{read_only:false; response_revision:28656; number_of_response:1; }","duration":"193.495924ms","start":"2026-06-26T04:25:05.152655Z","end":"2026-06-26T04:25:05.346151Z","steps":["trace[1154572375] 'process raft request' (duration: 98.529601ms)","trace[1154572375] 'compare' (duration: 93.122863ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:25:05.346652Z","caller":"traceutil/trace.go:171","msg":"trace[1875509108] transaction","detail":"{read_only:false; response_revision:28657; number_of_response:1; }","duration":"192.669336ms","start":"2026-06-26T04:25:05.153943Z","end":"2026-06-26T04:25:05.346613Z","steps":["trace[1875509108] 'process raft request' (duration: 192.457091ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:05.346793Z","caller":"traceutil/trace.go:171","msg":"trace[454655213] transaction","detail":"{read_only:false; response_revision:28658; number_of_response:1; }","duration":"191.782636ms","start":"2026-06-26T04:25:05.154986Z","end":"2026-06-26T04:25:05.346769Z","steps":["trace[454655213] 'process raft request' (duration: 191.558022ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:05.346745Z","caller":"traceutil/trace.go:171","msg":"trace[445080953] linearizableReadLoop","detail":"{readStateIndex:29960; appliedIndex:29958; }","duration":"192.976492ms","start":"2026-06-26T04:25:05.153753Z","end":"2026-06-26T04:25:05.346729Z","steps":["trace[445080953] 'read index received' (duration: 19.841189ms)","trace[445080953] 'applied index is now lower than readState.Index' (duration: 173.133933ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:25:05.346921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.144656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-e8b31db2-5013-480f-81f3-175d13706a98\" ","response":"range_response_count:1 size:1284"} {"level":"info","ts":"2026-06-26T04:25:05.346974Z","caller":"traceutil/trace.go:171","msg":"trace[1002142739] range","detail":"{range_begin:/registry/persistentvolumes/pvc-e8b31db2-5013-480f-81f3-175d13706a98; range_end:; response_count:1; response_revision:28658; }","duration":"193.248258ms","start":"2026-06-26T04:25:05.153716Z","end":"2026-06-26T04:25:05.346964Z","steps":["trace[1002142739] 'agreement among raft nodes before linearized reading' (duration: 193.057374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:05.347202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.823755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:25:05.347235Z","caller":"traceutil/trace.go:171","msg":"trace[588650134] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:28658; }","duration":"134.906387ms","start":"2026-06-26T04:25:05.212318Z","end":"2026-06-26T04:25:05.347224Z","steps":["trace[588650134] 'agreement among raft nodes before linearized reading' (duration: 134.847746ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:05.347242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.634274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3191"} {"level":"info","ts":"2026-06-26T04:25:05.347323Z","caller":"traceutil/trace.go:171","msg":"trace[1446186022] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:28658; }","duration":"191.758436ms","start":"2026-06-26T04:25:05.155544Z","end":"2026-06-26T04:25:05.347302Z","steps":["trace[1446186022] 'agreement among raft nodes before linearized reading' (duration: 191.4737ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:05.71573Z","caller":"traceutil/trace.go:171","msg":"trace[496187274] transaction","detail":"{read_only:false; response_revision:28660; number_of_response:1; }","duration":"359.433702ms","start":"2026-06-26T04:25:05.356246Z","end":"2026-06-26T04:25:05.715679Z","steps":["trace[496187274] 'process raft request' (duration: 338.480759ms)","trace[496187274] 'compare' (duration: 20.411121ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:25:05.715784Z","caller":"traceutil/trace.go:171","msg":"trace[65057290] linearizableReadLoop","detail":"{readStateIndex:29965; appliedIndex:29962; }","duration":"323.791622ms","start":"2026-06-26T04:25:05.391953Z","end":"2026-06-26T04:25:05.715744Z","steps":["trace[65057290] 'read index received' (duration: 25.391369ms)","trace[65057290] 'applied index is now lower than readState.Index' (duration: 298.399243ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:25:05.715947Z","caller":"traceutil/trace.go:171","msg":"trace[163046288] transaction","detail":"{read_only:false; response_revision:28661; number_of_response:1; }","duration":"358.214506ms","start":"2026-06-26T04:25:05.357718Z","end":"2026-06-26T04:25:05.715933Z","steps":["trace[163046288] 'process raft request' (duration: 357.885939ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:05.7161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:05.357705Z","time spent":"358.291277ms","remote":"127.0.0.1:46290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3100,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:25:05.716181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.16798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-26T04:25:05.715933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:05.356221Z","time spent":"359.603857ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:25:05.71633Z","caller":"traceutil/trace.go:171","msg":"trace[1465462434] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:28661; }","duration":"324.423485ms","start":"2026-06-26T04:25:05.391883Z","end":"2026-06-26T04:25:05.716307Z","steps":["trace[1465462434] 'agreement among raft nodes before linearized reading' (duration: 324.167479ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:05.716275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.953705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"warn","ts":"2026-06-26T04:25:05.716441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.64474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-26T04:25:05.716485Z","caller":"traceutil/trace.go:171","msg":"trace[1647871411] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:28661; }","duration":"262.258961ms","start":"2026-06-26T04:25:05.454178Z","end":"2026-06-26T04:25:05.716437Z","steps":["trace[1647871411] 'agreement among raft nodes before linearized reading' (duration: 261.855822ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:05.716465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.586901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-26T04:25:05.716469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:05.391855Z","time spent":"324.571958ms","remote":"127.0.0.1:41086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"info","ts":"2026-06-26T04:25:05.716585Z","caller":"traceutil/trace.go:171","msg":"trace[458777271] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:28661; }","duration":"307.777465ms","start":"2026-06-26T04:25:05.408781Z","end":"2026-06-26T04:25:05.716559Z","steps":["trace[458777271] 'agreement among raft nodes before linearized reading' (duration: 307.54095ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:05.716512Z","caller":"traceutil/trace.go:171","msg":"trace[560802439] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28661; }","duration":"300.741233ms","start":"2026-06-26T04:25:05.415756Z","end":"2026-06-26T04:25:05.716497Z","steps":["trace[560802439] 'agreement among raft nodes before linearized reading' (duration: 300.523478ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:05.716641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:05.408748Z","time spent":"307.880207ms","remote":"127.0.0.1:38548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-06-26T04:25:05.716694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:05.415747Z","time spent":"300.927637ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-26T04:25:05.716405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.466372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-26T04:25:05.716826Z","caller":"traceutil/trace.go:171","msg":"trace[1572230148] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28661; }","duration":"309.919302ms","start":"2026-06-26T04:25:05.406897Z","end":"2026-06-26T04:25:05.716817Z","steps":["trace[1572230148] 'agreement among raft nodes before linearized reading' (duration: 309.110654ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:05.716862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:05.406883Z","time spent":"309.965012ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-26T04:25:05.956365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.488486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:25:05.956562Z","caller":"traceutil/trace.go:171","msg":"trace[1659846141] transaction","detail":"{read_only:false; response_revision:28662; number_of_response:1; }","duration":"235.284778ms","start":"2026-06-26T04:25:05.721227Z","end":"2026-06-26T04:25:05.956512Z","steps":["trace[1659846141] 'process raft request' (duration: 132.537106ms)","trace[1659846141] 'compare' (duration: 102.306962ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:25:05.956648Z","caller":"traceutil/trace.go:171","msg":"trace[1231135378] transaction","detail":"{read_only:false; response_revision:28663; number_of_response:1; }","duration":"234.166053ms","start":"2026-06-26T04:25:05.72246Z","end":"2026-06-26T04:25:05.956626Z","steps":["trace[1231135378] 'process raft request' (duration: 234.054191ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:05.956639Z","caller":"traceutil/trace.go:171","msg":"trace[559631374] linearizableReadLoop","detail":"{readStateIndex:29966; appliedIndex:29965; }","duration":"235.168685ms","start":"2026-06-26T04:25:05.721442Z","end":"2026-06-26T04:25:05.956611Z","steps":["trace[559631374] 'read index received' (duration: 132.2772ms)","trace[559631374] 'applied index is now lower than readState.Index' (duration: 102.888465ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:25:05.956869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.40279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-445266ea-dbea-4b93-b395-ff21fc5e7bf3\" ","response":"range_response_count:1 size:1324"} {"level":"info","ts":"2026-06-26T04:25:05.956921Z","caller":"traceutil/trace.go:171","msg":"trace[246047276] range","detail":"{range_begin:/registry/persistentvolumes/pvc-445266ea-dbea-4b93-b395-ff21fc5e7bf3; range_end:; response_count:1; response_revision:28663; }","duration":"235.508192ms","start":"2026-06-26T04:25:05.721401Z","end":"2026-06-26T04:25:05.956909Z","steps":["trace[246047276] 'agreement among raft nodes before linearized reading' (duration: 235.286298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:05.95713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.053921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"warn","ts":"2026-06-26T04:25:05.957204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.0214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-06-26T04:25:05.957241Z","caller":"traceutil/trace.go:171","msg":"trace[780789120] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:28663; }","duration":"234.195324ms","start":"2026-06-26T04:25:05.723021Z","end":"2026-06-26T04:25:05.957216Z","steps":["trace[780789120] 'agreement among raft nodes before linearized reading' (duration: 233.889057ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:05.957265Z","caller":"traceutil/trace.go:171","msg":"trace[1052404023] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:28663; }","duration":"172.138463ms","start":"2026-06-26T04:25:05.785112Z","end":"2026-06-26T04:25:05.957251Z","steps":["trace[1052404023] 'agreement among raft nodes before linearized reading' (duration: 171.907008ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:06.385469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.953984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:25:06.385551Z","caller":"traceutil/trace.go:171","msg":"trace[494781603] linearizableReadLoop","detail":"{readStateIndex:29969; appliedIndex:29968; }","duration":"377.016912ms","start":"2026-06-26T04:25:06.008522Z","end":"2026-06-26T04:25:06.385539Z","steps":["trace[494781603] 'read index received' (duration: 126.953096ms)","trace[494781603] 'applied index is now lower than readState.Index' (duration: 250.063266ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:25:06.385607Z","caller":"traceutil/trace.go:171","msg":"trace[1757083941] transaction","detail":"{read_only:false; response_revision:28664; number_of_response:1; }","duration":"413.358639ms","start":"2026-06-26T04:25:05.972241Z","end":"2026-06-26T04:25:06.3856Z","steps":["trace[1757083941] 'process raft request' (duration: 163.2211ms)","trace[1757083941] 'compare' (duration: 249.597857ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:25:06.385642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:05.972222Z","time spent":"413.404969ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:25:06.385863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.405243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-26T04:25:06.385904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.172651ms","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-06-26T04:25:06.385921Z","caller":"traceutil/trace.go:171","msg":"trace[594003095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28665; }","duration":"128.232722ms","start":"2026-06-26T04:25:06.257681Z","end":"2026-06-26T04:25:06.385914Z","steps":["trace[594003095] 'agreement among raft nodes before linearized reading' (duration: 128.150591ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:06.385912Z","caller":"traceutil/trace.go:171","msg":"trace[2123930628] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:28665; }","duration":"286.493904ms","start":"2026-06-26T04:25:06.099403Z","end":"2026-06-26T04:25:06.385897Z","steps":["trace[2123930628] 'agreement among raft nodes before linearized reading' (duration: 286.418302ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:06.38599Z","caller":"traceutil/trace.go:171","msg":"trace[211168311] transaction","detail":"{read_only:false; response_revision:28665; number_of_response:1; }","duration":"376.293166ms","start":"2026-06-26T04:25:06.009691Z","end":"2026-06-26T04:25:06.385984Z","steps":["trace[211168311] 'process raft request' (duration: 375.888907ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:06.38602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:06.009666Z","time spent":"376.333447ms","remote":"127.0.0.1:38054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:25:06.385982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.798363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9772"} {"level":"info","ts":"2026-06-26T04:25:06.386073Z","caller":"traceutil/trace.go:171","msg":"trace[705463380] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:28665; }","duration":"423.920605ms","start":"2026-06-26T04:25:05.962131Z","end":"2026-06-26T04:25:06.386052Z","steps":["trace[705463380] 'agreement among raft nodes before linearized reading' (duration: 423.590038ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:06.386105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:06.010734Z","time spent":"375.370026ms","remote":"127.0.0.1:38092","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-26T04:25:06.386112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:05.962124Z","time spent":"423.980877ms","remote":"127.0.0.1:38440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":9794,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" "} {"level":"warn","ts":"2026-06-26T04:25:06.386157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.492629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-26T04:25:06.386202Z","caller":"traceutil/trace.go:171","msg":"trace[986373769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28665; }","duration":"135.582171ms","start":"2026-06-26T04:25:06.25061Z","end":"2026-06-26T04:25:06.386193Z","steps":["trace[986373769] 'agreement among raft nodes before linearized reading' (duration: 135.464159ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:06.3864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.264255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3118"} {"level":"info","ts":"2026-06-26T04:25:06.386437Z","caller":"traceutil/trace.go:171","msg":"trace[281863712] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:28665; }","duration":"240.338656ms","start":"2026-06-26T04:25:06.146088Z","end":"2026-06-26T04:25:06.386427Z","steps":["trace[281863712] 'agreement among raft nodes before linearized reading' (duration: 240.229504ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:06.386509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.216864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:25:06.386531Z","caller":"traceutil/trace.go:171","msg":"trace[274192609] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:28665; }","duration":"228.280845ms","start":"2026-06-26T04:25:06.158242Z","end":"2026-06-26T04:25:06.386523Z","steps":["trace[274192609] 'agreement among raft nodes before linearized reading' (duration: 228.241294ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:06.386564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.909636ms","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-06-26T04:25:06.386654Z","caller":"traceutil/trace.go:171","msg":"trace[173136652] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28665; }","duration":"128.058968ms","start":"2026-06-26T04:25:06.258571Z","end":"2026-06-26T04:25:06.38663Z","steps":["trace[173136652] 'agreement among raft nodes before linearized reading' (duration: 127.726541ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:06.721233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.818784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:25:06.721353Z","caller":"traceutil/trace.go:171","msg":"trace[2044339355] linearizableReadLoop","detail":"{readStateIndex:29976; appliedIndex:29975; }","duration":"281.987107ms","start":"2026-06-26T04:25:06.439347Z","end":"2026-06-26T04:25:06.721334Z","steps":["trace[2044339355] 'read index received' (duration: 123.815567ms)","trace[2044339355] 'applied index is now lower than readState.Index' (duration: 158.16894ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:25:06.721545Z","caller":"traceutil/trace.go:171","msg":"trace[1750949621] transaction","detail":"{read_only:false; response_revision:28670; number_of_response:1; }","duration":"286.505345ms","start":"2026-06-26T04:25:06.435024Z","end":"2026-06-26T04:25:06.721529Z","steps":["trace[1750949621] 'process raft request' (duration: 128.288314ms)","trace[1750949621] 'compare' (duration: 157.061826ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:25:06.721846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.168346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-26T04:25:06.721917Z","caller":"traceutil/trace.go:171","msg":"trace[1600700400] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:28670; }","duration":"236.294369ms","start":"2026-06-26T04:25:06.485604Z","end":"2026-06-26T04:25:06.721898Z","steps":["trace[1600700400] 'agreement among raft nodes before linearized reading' (duration: 236.034324ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:06.721898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.521919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10798"} {"level":"info","ts":"2026-06-26T04:25:06.721983Z","caller":"traceutil/trace.go:171","msg":"trace[945911558] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:28670; }","duration":"282.638562ms","start":"2026-06-26T04:25:06.439326Z","end":"2026-06-26T04:25:06.721965Z","steps":["trace[945911558] 'agreement among raft nodes before linearized reading' (duration: 282.293264ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:07.200963Z","caller":"traceutil/trace.go:171","msg":"trace[1566379241] transaction","detail":"{read_only:false; response_revision:28671; number_of_response:1; }","duration":"344.724844ms","start":"2026-06-26T04:25:06.856213Z","end":"2026-06-26T04:25:07.200938Z","steps":["trace[1566379241] 'process raft request' (duration: 344.546841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:07.201115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:06.856186Z","time spent":"344.849676ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:25:07.203928Z","caller":"traceutil/trace.go:171","msg":"trace[1925535118] transaction","detail":"{read_only:false; response_revision:28672; number_of_response:1; }","duration":"346.642556ms","start":"2026-06-26T04:25:06.857251Z","end":"2026-06-26T04:25:07.203894Z","steps":["trace[1925535118] 'process raft request' (duration: 345.936701ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:07.204101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:06.857227Z","time spent":"346.781698ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:25:07.204282Z","caller":"traceutil/trace.go:171","msg":"trace[1583818198] transaction","detail":"{read_only:false; response_revision:28673; number_of_response:1; }","duration":"345.112212ms","start":"2026-06-26T04:25:06.859153Z","end":"2026-06-26T04:25:07.204265Z","steps":["trace[1583818198] 'process raft request' (duration: 344.167492ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:07.204369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:06.859109Z","time spent":"345.216604ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:25:07.96803Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7c53a18098324dc9","local-member-applied-index":30004,"local-member-snapshot-index":20003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-26T04:25:07.973493Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-06-26T04:25:07.97362Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"warn","ts":"2026-06-26T04:25:27.965705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.184508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:25:27.965794Z","caller":"traceutil/trace.go:171","msg":"trace[872446688] linearizableReadLoop","detail":"{readStateIndex:30205; appliedIndex:30204; }","duration":"307.09894ms","start":"2026-06-26T04:25:27.658682Z","end":"2026-06-26T04:25:27.965781Z","steps":["trace[872446688] 'read index received' (duration: 26.654796ms)","trace[872446688] 'applied index is now lower than readState.Index' (duration: 280.443054ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:25:27.965887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.208732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-06-26T04:25:27.965906Z","caller":"traceutil/trace.go:171","msg":"trace[1879835199] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28891; }","duration":"307.265683ms","start":"2026-06-26T04:25:27.658633Z","end":"2026-06-26T04:25:27.965899Z","steps":["trace[1879835199] 'agreement among raft nodes before linearized reading' (duration: 307.176261ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:27.965925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:27.658611Z","time spent":"307.309914ms","remote":"127.0.0.1:38114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":669,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-06-26T04:25:27.966048Z","caller":"traceutil/trace.go:171","msg":"trace[1853247707] transaction","detail":"{read_only:false; response_revision:28891; number_of_response:1; }","duration":"324.410994ms","start":"2026-06-26T04:25:27.64163Z","end":"2026-06-26T04:25:27.966041Z","steps":["trace[1853247707] 'process raft request' (duration: 43.818037ms)","trace[1853247707] 'compare' (duration: 279.880192ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:25:27.966081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:27.641609Z","time spent":"324.456805ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:25:28.383032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.011864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:25:28.383144Z","caller":"traceutil/trace.go:171","msg":"trace[191604945] linearizableReadLoop","detail":"{readStateIndex:30206; appliedIndex:30205; }","duration":"391.897824ms","start":"2026-06-26T04:25:27.991228Z","end":"2026-06-26T04:25:28.383126Z","steps":["trace[191604945] 'read index received' (duration: 284.785738ms)","trace[191604945] 'applied index is now lower than readState.Index' (duration: 107.110766ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:25:28.383254Z","caller":"traceutil/trace.go:171","msg":"trace[1578448732] transaction","detail":"{read_only:false; response_revision:28892; number_of_response:1; }","duration":"409.213198ms","start":"2026-06-26T04:25:27.974029Z","end":"2026-06-26T04:25:28.383243Z","steps":["trace[1578448732] 'process raft request' (duration: 301.898408ms)","trace[1578448732] 'compare' (duration: 106.791499ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:25:28.38346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.643087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-06-26T04:25:28.383539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:27.974006Z","time spent":"409.492774ms","remote":"127.0.0.1:38114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:25:28.383548Z","caller":"traceutil/trace.go:171","msg":"trace[527234527] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28892; }","duration":"317.796781ms","start":"2026-06-26T04:25:28.065726Z","end":"2026-06-26T04:25:28.383523Z","steps":["trace[527234527] 'agreement among raft nodes before linearized reading' (duration: 317.540956ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:28.383598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:28.0657Z","time spent":"317.889183ms","remote":"127.0.0.1:38254","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-06-26T04:25:28.38358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.319223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"warn","ts":"2026-06-26T04:25:28.383607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.148192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-26T04:25:28.383681Z","caller":"traceutil/trace.go:171","msg":"trace[1696869596] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28892; }","duration":"314.295115ms","start":"2026-06-26T04:25:28.069373Z","end":"2026-06-26T04:25:28.383668Z","steps":["trace[1696869596] 'agreement among raft nodes before linearized reading' (duration: 314.06764ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:28.383695Z","caller":"traceutil/trace.go:171","msg":"trace[249337930] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28892; }","duration":"392.463425ms","start":"2026-06-26T04:25:27.99121Z","end":"2026-06-26T04:25:28.383674Z","steps":["trace[249337930] 'agreement among raft nodes before linearized reading' (duration: 392.20839ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:28.383714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:28.069337Z","time spent":"314.372247ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-26T04:25:28.383734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:27.9912Z","time spent":"392.524267ms","remote":"127.0.0.1:38114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":684,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-06-26T04:25:28.383702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.374283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11498"} {"level":"info","ts":"2026-06-26T04:25:28.383809Z","caller":"traceutil/trace.go:171","msg":"trace[1319787264] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28892; }","duration":"287.575778ms","start":"2026-06-26T04:25:28.096205Z","end":"2026-06-26T04:25:28.38378Z","steps":["trace[1319787264] 'agreement among raft nodes before linearized reading' (duration: 287.21058ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:28.64136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.585305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:25:28.641538Z","caller":"traceutil/trace.go:171","msg":"trace[1323588155] transaction","detail":"{read_only:false; response_revision:28893; number_of_response:1; }","duration":"254.574193ms","start":"2026-06-26T04:25:28.386932Z","end":"2026-06-26T04:25:28.641507Z","steps":["trace[1323588155] 'process raft request' (duration: 116.773944ms)","trace[1323588155] 'compare' (duration: 137.462872ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:25:28.641742Z","caller":"traceutil/trace.go:171","msg":"trace[393080769] transaction","detail":"{read_only:false; response_revision:28894; number_of_response:1; }","duration":"254.600395ms","start":"2026-06-26T04:25:28.387102Z","end":"2026-06-26T04:25:28.641703Z","steps":["trace[393080769] 'process raft request' (duration: 254.337249ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:28.642035Z","caller":"traceutil/trace.go:171","msg":"trace[1643846533] linearizableReadLoop","detail":"{readStateIndex:30208; appliedIndex:30206; }","duration":"254.549944ms","start":"2026-06-26T04:25:28.387471Z","end":"2026-06-26T04:25:28.642021Z","steps":["trace[1643846533] 'read index received' (duration: 116.294924ms)","trace[1643846533] 'applied index is now lower than readState.Index' (duration: 138.25417ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:25:28.642107Z","caller":"traceutil/trace.go:171","msg":"trace[1957516769] transaction","detail":"{read_only:false; response_revision:28895; number_of_response:1; }","duration":"201.423866ms","start":"2026-06-26T04:25:28.440665Z","end":"2026-06-26T04:25:28.642089Z","steps":["trace[1957516769] 'process raft request' (duration: 200.813292ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:28.642181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.708677ms","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-06-26T04:25:28.642226Z","caller":"traceutil/trace.go:171","msg":"trace[103857645] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28895; }","duration":"254.749818ms","start":"2026-06-26T04:25:28.38745Z","end":"2026-06-26T04:25:28.642199Z","steps":["trace[103857645] 'agreement among raft nodes before linearized reading' (duration: 254.638746ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:28.64421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.607938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-06-26T04:25:28.644338Z","caller":"traceutil/trace.go:171","msg":"trace[879692888] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28896; }","duration":"256.804102ms","start":"2026-06-26T04:25:28.387502Z","end":"2026-06-26T04:25:28.644306Z","steps":["trace[879692888] 'agreement among raft nodes before linearized reading' (duration: 256.486725ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:28.644499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.942642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11498"} {"level":"info","ts":"2026-06-26T04:25:28.644552Z","caller":"traceutil/trace.go:171","msg":"trace[1434751387] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28896; }","duration":"254.019063ms","start":"2026-06-26T04:25:28.390521Z","end":"2026-06-26T04:25:28.64454Z","steps":["trace[1434751387] 'agreement among raft nodes before linearized reading' (duration: 253.90379ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:28.926519Z","caller":"traceutil/trace.go:171","msg":"trace[1435853428] transaction","detail":"{read_only:false; response_revision:28897; number_of_response:1; }","duration":"277.627723ms","start":"2026-06-26T04:25:28.648858Z","end":"2026-06-26T04:25:28.926485Z","steps":["trace[1435853428] 'process raft request' (duration: 262.258301ms)","trace[1435853428] 'compare' (duration: 15.004254ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:25:29.087023Z","caller":"traceutil/trace.go:171","msg":"trace[1367315115] transaction","detail":"{read_only:false; response_revision:28898; number_of_response:1; }","duration":"437.189293ms","start":"2026-06-26T04:25:28.649792Z","end":"2026-06-26T04:25:29.086981Z","steps":["trace[1367315115] 'process raft request' (duration: 436.867456ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:29.087053Z","caller":"traceutil/trace.go:171","msg":"trace[1982621465] transaction","detail":"{read_only:false; response_revision:28899; number_of_response:1; }","duration":"436.956137ms","start":"2026-06-26T04:25:28.650059Z","end":"2026-06-26T04:25:29.087015Z","steps":["trace[1982621465] 'process raft request' (duration: 436.809444ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:29.087081Z","caller":"traceutil/trace.go:171","msg":"trace[713913572] transaction","detail":"{read_only:false; response_revision:28900; number_of_response:1; }","duration":"436.669892ms","start":"2026-06-26T04:25:28.650392Z","end":"2026-06-26T04:25:29.087062Z","steps":["trace[713913572] 'process raft request' (duration: 436.528318ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:29.08724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:28.649775Z","time spent":"437.351248ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:25:29.087245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:28.650046Z","time spent":"437.090921ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:25:29.087421Z","caller":"traceutil/trace.go:171","msg":"trace[571531537] transaction","detail":"{read_only:false; response_revision:28901; number_of_response:1; }","duration":"434.956796ms","start":"2026-06-26T04:25:28.652447Z","end":"2026-06-26T04:25:29.087403Z","steps":["trace[571531537] 'process raft request' (duration: 434.537186ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:29.087238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:28.650381Z","time spent":"436.764734ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:25:29.087508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:28.652438Z","time spent":"435.023297ms","remote":"127.0.0.1:38114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:25:29.087601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.520244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:25:29.087665Z","caller":"traceutil/trace.go:171","msg":"trace[976054265] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:28902; }","duration":"396.616196ms","start":"2026-06-26T04:25:28.691033Z","end":"2026-06-26T04:25:29.087649Z","steps":["trace[976054265] 'agreement among raft nodes before linearized reading' (duration: 396.514004ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:29.08771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:28.691017Z","time spent":"396.678698ms","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true "} {"level":"info","ts":"2026-06-26T04:25:29.087687Z","caller":"traceutil/trace.go:171","msg":"trace[2146527817] transaction","detail":"{read_only:false; response_revision:28902; number_of_response:1; }","duration":"385.531987ms","start":"2026-06-26T04:25:28.702072Z","end":"2026-06-26T04:25:29.087604Z","steps":["trace[2146527817] 'process raft request' (duration: 385.176649ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:29.087929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:28.702054Z","time spent":"385.734591ms","remote":"127.0.0.1:38440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:25:29.087507Z","caller":"traceutil/trace.go:171","msg":"trace[994449780] linearizableReadLoop","detail":"{readStateIndex:30215; appliedIndex:30210; }","duration":"396.419452ms","start":"2026-06-26T04:25:28.691066Z","end":"2026-06-26T04:25:29.087485Z","steps":["trace[994449780] 'read index received' (duration: 220.066189ms)","trace[994449780] 'applied index is now lower than readState.Index' (duration: 176.351553ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:25:29.36291Z","caller":"traceutil/trace.go:171","msg":"trace[1091189069] transaction","detail":"{read_only:false; number_of_response:0; response_revision:28902; }","duration":"268.186359ms","start":"2026-06-26T04:25:29.094691Z","end":"2026-06-26T04:25:29.362878Z","steps":["trace[1091189069] 'process raft request' (duration: 268.051956ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:29.597535Z","caller":"traceutil/trace.go:171","msg":"trace[1200054684] transaction","detail":"{read_only:false; response_revision:28904; number_of_response:1; }","duration":"263.496387ms","start":"2026-06-26T04:25:29.334017Z","end":"2026-06-26T04:25:29.597513Z","steps":["trace[1200054684] 'process raft request' (duration: 263.417466ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:29.597541Z","caller":"traceutil/trace.go:171","msg":"trace[992545527] transaction","detail":"{read_only:false; response_revision:28903; number_of_response:1; }","duration":"501.955953ms","start":"2026-06-26T04:25:29.095553Z","end":"2026-06-26T04:25:29.597509Z","steps":["trace[992545527] 'process raft request' (duration: 501.730489ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:29.597828Z","caller":"traceutil/trace.go:171","msg":"trace[183396274] transaction","detail":"{read_only:false; response_revision:28905; number_of_response:1; }","duration":"247.163894ms","start":"2026-06-26T04:25:29.350649Z","end":"2026-06-26T04:25:29.597813Z","steps":["trace[183396274] 'process raft request' (duration: 246.828617ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:29.597862Z","caller":"traceutil/trace.go:171","msg":"trace[703256755] linearizableReadLoop","detail":"{readStateIndex:30218; appliedIndex:30216; }","duration":"317.466504ms","start":"2026-06-26T04:25:29.280376Z","end":"2026-06-26T04:25:29.597842Z","steps":["trace[703256755] 'read index received' (duration: 82.423552ms)","trace[703256755] 'applied index is now lower than readState.Index' (duration: 235.041882ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:25:29.597897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:29.095539Z","time spent":"502.245219ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:25:29.597973Z","caller":"traceutil/trace.go:171","msg":"trace[473058656] transaction","detail":"{read_only:false; response_revision:28906; number_of_response:1; }","duration":"247.229835ms","start":"2026-06-26T04:25:29.350733Z","end":"2026-06-26T04:25:29.597963Z","steps":["trace[473058656] 'process raft request' (duration: 246.963339ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:29.598092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.712309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-26T04:25:29.598128Z","caller":"traceutil/trace.go:171","msg":"trace[730456729] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:28907; }","duration":"317.816582ms","start":"2026-06-26T04:25:29.2803Z","end":"2026-06-26T04:25:29.598117Z","steps":["trace[730456729] 'agreement among raft nodes before linearized reading' (duration: 317.70052ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:29.598153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:29.280273Z","time spent":"317.872384ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":60,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-06-26T04:25:29.598183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.816576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:13486"} {"level":"info","ts":"2026-06-26T04:25:29.598233Z","caller":"traceutil/trace.go:171","msg":"trace[1666584103] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:28907; }","duration":"233.897588ms","start":"2026-06-26T04:25:29.364303Z","end":"2026-06-26T04:25:29.598201Z","steps":["trace[1666584103] 'agreement among raft nodes before linearized reading' (duration: 233.781796ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:29.598298Z","caller":"traceutil/trace.go:171","msg":"trace[2110191211] transaction","detail":"{read_only:false; response_revision:28907; number_of_response:1; }","duration":"246.942449ms","start":"2026-06-26T04:25:29.351346Z","end":"2026-06-26T04:25:29.598288Z","steps":["trace[2110191211] 'process raft request' (duration: 246.457329ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:29.801373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.554701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:25:29.80171Z","caller":"traceutil/trace.go:171","msg":"trace[500666227] transaction","detail":"{read_only:false; response_revision:28908; number_of_response:1; }","duration":"198.985903ms","start":"2026-06-26T04:25:29.602692Z","end":"2026-06-26T04:25:29.801678Z","steps":["trace[500666227] 'process raft request' (duration: 62.994912ms)","trace[500666227] 'compare' (duration: 134.937198ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:25:29.858393Z","caller":"traceutil/trace.go:171","msg":"trace[1547195428] transaction","detail":"{read_only:false; response_revision:28909; number_of_response:1; }","duration":"250.949036ms","start":"2026-06-26T04:25:29.607412Z","end":"2026-06-26T04:25:29.858362Z","steps":["trace[1547195428] 'process raft request' (duration: 250.335283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:30.112361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.836619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4831"} {"level":"info","ts":"2026-06-26T04:25:30.112467Z","caller":"traceutil/trace.go:171","msg":"trace[1522281292] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:28909; }","duration":"205.031424ms","start":"2026-06-26T04:25:29.907411Z","end":"2026-06-26T04:25:30.112442Z","steps":["trace[1522281292] 'range keys from in-memory index tree' (duration: 204.610294ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:30.856257Z","caller":"traceutil/trace.go:171","msg":"trace[1840736715] transaction","detail":"{read_only:false; response_revision:28910; number_of_response:1; }","duration":"207.102868ms","start":"2026-06-26T04:25:30.649125Z","end":"2026-06-26T04:25:30.856228Z","steps":["trace[1840736715] 'process raft request' (duration: 206.860683ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:30.900306Z","caller":"traceutil/trace.go:171","msg":"trace[525126037] transaction","detail":"{read_only:false; response_revision:28911; number_of_response:1; }","duration":"250.534338ms","start":"2026-06-26T04:25:30.649724Z","end":"2026-06-26T04:25:30.900258Z","steps":["trace[525126037] 'process raft request' (duration: 250.244232ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:30.900297Z","caller":"traceutil/trace.go:171","msg":"trace[461148556] transaction","detail":"{read_only:false; response_revision:28912; number_of_response:1; }","duration":"250.490857ms","start":"2026-06-26T04:25:30.649729Z","end":"2026-06-26T04:25:30.90022Z","steps":["trace[461148556] 'process raft request' (duration: 250.422306ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:31.057082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.77731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-06-26T04:25:31.057199Z","caller":"traceutil/trace.go:171","msg":"trace[1069731760] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28912; }","duration":"125.933183ms","start":"2026-06-26T04:25:30.931243Z","end":"2026-06-26T04:25:31.057176Z","steps":["trace[1069731760] 'range keys from in-memory index tree' (duration: 125.605766ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:31.288897Z","caller":"traceutil/trace.go:171","msg":"trace[173386556] linearizableReadLoop","detail":"{readStateIndex:30230; appliedIndex:30229; }","duration":"157.097557ms","start":"2026-06-26T04:25:31.13177Z","end":"2026-06-26T04:25:31.288868Z","steps":["trace[173386556] 'read index received' (duration: 136.918321ms)","trace[173386556] 'applied index is now lower than readState.Index' (duration: 20.177566ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:25:31.289202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.412694ms","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-06-26T04:25:31.28934Z","caller":"traceutil/trace.go:171","msg":"trace[1217290705] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28914; }","duration":"157.580178ms","start":"2026-06-26T04:25:31.131714Z","end":"2026-06-26T04:25:31.289295Z","steps":["trace[1217290705] 'agreement among raft nodes before linearized reading' (duration: 157.264961ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:31.293248Z","caller":"traceutil/trace.go:171","msg":"trace[1502217631] transaction","detail":"{read_only:false; response_revision:28915; number_of_response:1; }","duration":"160.557862ms","start":"2026-06-26T04:25:31.132642Z","end":"2026-06-26T04:25:31.2932Z","steps":["trace[1502217631] 'process raft request' (duration: 160.240425ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:31.293314Z","caller":"traceutil/trace.go:171","msg":"trace[247789479] transaction","detail":"{read_only:false; response_revision:28916; number_of_response:1; }","duration":"159.811286ms","start":"2026-06-26T04:25:31.133477Z","end":"2026-06-26T04:25:31.293288Z","steps":["trace[247789479] 'process raft request' (duration: 159.606881ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:31.293499Z","caller":"traceutil/trace.go:171","msg":"trace[818771334] transaction","detail":"{read_only:false; response_revision:28917; number_of_response:1; }","duration":"159.328236ms","start":"2026-06-26T04:25:31.13414Z","end":"2026-06-26T04:25:31.293468Z","steps":["trace[818771334] 'process raft request' (duration: 159.027559ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:31.755433Z","caller":"traceutil/trace.go:171","msg":"trace[353309557] transaction","detail":"{read_only:false; response_revision:28921; number_of_response:1; }","duration":"151.412134ms","start":"2026-06-26T04:25:31.603988Z","end":"2026-06-26T04:25:31.755401Z","steps":["trace[353309557] 'process raft request' (duration: 97.790545ms)","trace[353309557] 'compare' (duration: 53.095288ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:25:31.755729Z","caller":"traceutil/trace.go:171","msg":"trace[666045866] transaction","detail":"{read_only:false; response_revision:28922; number_of_response:1; }","duration":"151.672069ms","start":"2026-06-26T04:25:31.604029Z","end":"2026-06-26T04:25:31.755701Z","steps":["trace[666045866] 'process raft request' (duration: 151.149038ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:31.756251Z","caller":"traceutil/trace.go:171","msg":"trace[915153644] linearizableReadLoop","detail":"{readStateIndex:30238; appliedIndex:30235; }","duration":"151.252021ms","start":"2026-06-26T04:25:31.604987Z","end":"2026-06-26T04:25:31.756239Z","steps":["trace[915153644] 'read index received' (duration: 48.868797ms)","trace[915153644] 'applied index is now lower than readState.Index' (duration: 102.382144ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:25:31.75634Z","caller":"traceutil/trace.go:171","msg":"trace[1484562550] transaction","detail":"{read_only:false; response_revision:28923; number_of_response:1; }","duration":"148.663734ms","start":"2026-06-26T04:25:31.607654Z","end":"2026-06-26T04:25:31.756318Z","steps":["trace[1484562550] 'process raft request' (duration: 147.639783ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:31.75658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.942626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:25:31.756627Z","caller":"traceutil/trace.go:171","msg":"trace[118353657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28923; }","duration":"140.030938ms","start":"2026-06-26T04:25:31.616581Z","end":"2026-06-26T04:25:31.756612Z","steps":["trace[118353657] 'agreement among raft nodes before linearized reading' (duration: 139.945506ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:31.756581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.588258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-06-26T04:25:31.756736Z","caller":"traceutil/trace.go:171","msg":"trace[1797648358] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28923; }","duration":"151.730571ms","start":"2026-06-26T04:25:31.60498Z","end":"2026-06-26T04:25:31.75671Z","steps":["trace[1797648358] 'agreement among raft nodes before linearized reading' (duration: 151.484046ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:59.134988Z","caller":"traceutil/trace.go:171","msg":"trace[1891839749] transaction","detail":"{read_only:false; response_revision:29309; number_of_response:1; }","duration":"132.290982ms","start":"2026-06-26T04:25:59.002665Z","end":"2026-06-26T04:25:59.134956Z","steps":["trace[1891839749] 'process raft request' (duration: 132.087647ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:59.250155Z","caller":"traceutil/trace.go:171","msg":"trace[1949676524] linearizableReadLoop","detail":"{readStateIndex:30637; appliedIndex:30635; }","duration":"211.31368ms","start":"2026-06-26T04:25:59.03882Z","end":"2026-06-26T04:25:59.250133Z","steps":["trace[1949676524] 'read index received' (duration: 95.941925ms)","trace[1949676524] 'applied index is now lower than readState.Index' (duration: 115.366635ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:25:59.250442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.594666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-26T04:25:59.250484Z","caller":"traceutil/trace.go:171","msg":"trace[70180140] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:29310; }","duration":"211.696028ms","start":"2026-06-26T04:25:59.038777Z","end":"2026-06-26T04:25:59.250473Z","steps":["trace[70180140] 'agreement among raft nodes before linearized reading' (duration: 211.478644ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:59.250748Z","caller":"traceutil/trace.go:171","msg":"trace[2103538020] transaction","detail":"{read_only:false; response_revision:29310; number_of_response:1; }","duration":"244.20434ms","start":"2026-06-26T04:25:59.006531Z","end":"2026-06-26T04:25:59.250736Z","steps":["trace[2103538020] 'process raft request' (duration: 241.988542ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:25:59.570565Z","caller":"traceutil/trace.go:171","msg":"trace[2142657871] linearizableReadLoop","detail":"{readStateIndex:30638; appliedIndex:30637; }","duration":"115.946326ms","start":"2026-06-26T04:25:59.454587Z","end":"2026-06-26T04:25:59.570533Z","steps":["trace[2142657871] 'read index received' (duration: 111.182343ms)","trace[2142657871] 'applied index is now lower than readState.Index' (duration: 4.762783ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:25:59.570588Z","caller":"traceutil/trace.go:171","msg":"trace[1942989506] transaction","detail":"{read_only:false; response_revision:29311; number_of_response:1; }","duration":"306.15871ms","start":"2026-06-26T04:25:59.264389Z","end":"2026-06-26T04:25:59.570548Z","steps":["trace[1942989506] 'process raft request' (duration: 301.471588ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:59.570816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:25:59.264364Z","time spent":"306.346863ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:25:59.570884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.01788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-26T04:25:59.570922Z","caller":"traceutil/trace.go:171","msg":"trace[1487092994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:29311; }","duration":"105.144933ms","start":"2026-06-26T04:25:59.465767Z","end":"2026-06-26T04:25:59.570912Z","steps":["trace[1487092994] 'agreement among raft nodes before linearized reading' (duration: 104.942528ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:25:59.570929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.305624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-26T04:25:59.571042Z","caller":"traceutil/trace.go:171","msg":"trace[9277260] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:29311; }","duration":"116.511019ms","start":"2026-06-26T04:25:59.454501Z","end":"2026-06-26T04:25:59.571012Z","steps":["trace[9277260] 'agreement among raft nodes before linearized reading' (duration: 116.142541ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:02.627377Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27264} {"level":"info","ts":"2026-06-26T04:28:02.782671Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27264,"took":"149.905838ms","hash":3799597458,"current-db-size-bytes":61153280,"current-db-size":"61 MB","current-db-size-in-use-bytes":37122048,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-06-26T04:28:02.782744Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3799597458,"revision":27264,"compact-revision":23657} {"level":"info","ts":"2026-06-26T04:33:02.633997Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30610} {"level":"info","ts":"2026-06-26T04:33:02.781715Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30610,"took":"143.115571ms","hash":3201801757,"current-db-size-bytes":61153280,"current-db-size":"61 MB","current-db-size-in-use-bytes":36761600,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-06-26T04:33:02.781791Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3201801757,"revision":30610,"compact-revision":27264} {"level":"info","ts":"2026-06-26T04:38:02.639855Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33556} {"level":"info","ts":"2026-06-26T04:38:02.779339Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33556,"took":"134.833386ms","hash":2329337355,"current-db-size-bytes":61153280,"current-db-size":"61 MB","current-db-size-in-use-bytes":34549760,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-26T04:38:02.779413Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2329337355,"revision":33556,"compact-revision":30610} {"level":"info","ts":"2026-06-26T04:41:46.61789Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7c53a18098324dc9","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-26T04:41:46.623952Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-06-26T04:41:46.624115Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-06-26T04:43:02.645984Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36228} {"level":"info","ts":"2026-06-26T04:43:02.775576Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36228,"took":"124.79709ms","hash":4183568447,"current-db-size-bytes":61153280,"current-db-size":"61 MB","current-db-size-in-use-bytes":34574336,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-26T04:43:02.775651Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4183568447,"revision":36228,"compact-revision":33556} {"level":"info","ts":"2026-06-26T04:48:02.652068Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38939} {"level":"info","ts":"2026-06-26T04:48:02.836318Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38939,"took":"178.698617ms","hash":2344324001,"current-db-size-bytes":61153280,"current-db-size":"61 MB","current-db-size-in-use-bytes":35201024,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-26T04:48:02.836393Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2344324001,"revision":38939,"compact-revision":36228} {"level":"info","ts":"2026-06-26T04:53:02.661368Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41681} {"level":"info","ts":"2026-06-26T04:53:02.808515Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41681,"took":"142.511145ms","hash":15709278,"current-db-size-bytes":61153280,"current-db-size":"61 MB","current-db-size-in-use-bytes":34119680,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-26T04:53:02.808593Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":15709278,"revision":41681,"compact-revision":38939}