{"level":"warn","ts":"2026-10-14T17:42:05.313262Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T17:42:05.313346Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.91: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://162.253.55.91:2380","--initial-cluster=instance=https://162.253.55.91:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.91:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.91: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-10-14T17:42:05.313405Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T17:42:05.313418Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.91:2380"]} {"level":"info","ts":"2026-10-14T17:42:05.313438Z","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-10-14T17:42:05.314028Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"]} {"level":"info","ts":"2026-10-14T17:42:05.314111Z","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://162.253.55.91:2380"],"listen-peer-urls":["https://162.253.55.91:2380"],"advertise-client-urls":["https://162.253.55.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.91: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-10-14T17:42:05.318958Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.539573ms"} {"level":"info","ts":"2026-10-14T17:42:05.325738Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8a5b61ed9120d6b3","cluster-id":"9e0c1e8055c016ad"} {"level":"info","ts":"2026-10-14T17:42:05.325879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T17:42:05.325916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became follower at term 0"} {"level":"info","ts":"2026-10-14T17:42:05.325936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8a5b61ed9120d6b3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T17:42:05.325959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became follower at term 1"} {"level":"info","ts":"2026-10-14T17:42:05.326014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=(9969669873084716723)"} {"level":"warn","ts":"2026-10-14T17:42:05.339057Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T17:42:05.341292Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T17:42:05.343169Z","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-10-14T17:42:05.346393Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8a5b61ed9120d6b3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T17:42:05.346716Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8a5b61ed9120d6b3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T17:42:05.346781Z","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-10-14T17:42:05.346937Z","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-10-14T17:42:05.346955Z","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-10-14T17:42:05.349089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=(9969669873084716723)"} {"level":"info","ts":"2026-10-14T17:42:05.351376Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9e0c1e8055c016ad","local-member-id":"8a5b61ed9120d6b3","added-peer-id":"8a5b61ed9120d6b3","added-peer-peer-urls":["https://162.253.55.91:2380"]} {"level":"info","ts":"2026-10-14T17:42:05.356404Z","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-10-14T17:42:05.35656Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.91:2380"} {"level":"info","ts":"2026-10-14T17:42:05.356619Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.91:2380"} {"level":"info","ts":"2026-10-14T17:42:05.357218Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8a5b61ed9120d6b3","initial-advertise-peer-urls":["https://162.253.55.91:2380"],"listen-peer-urls":["https://162.253.55.91:2380"],"advertise-client-urls":["https://162.253.55.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T17:42:05.357311Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T17:42:05.527014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T17:42:05.527066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T17:42:05.52711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 received MsgPreVoteResp from 8a5b61ed9120d6b3 at term 1"} {"level":"info","ts":"2026-10-14T17:42:05.527135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became candidate at term 2"} {"level":"info","ts":"2026-10-14T17:42:05.52715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 received MsgVoteResp from 8a5b61ed9120d6b3 at term 2"} {"level":"info","ts":"2026-10-14T17:42:05.527167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became leader at term 2"} {"level":"info","ts":"2026-10-14T17:42:05.527181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8a5b61ed9120d6b3 elected leader 8a5b61ed9120d6b3 at term 2"} {"level":"info","ts":"2026-10-14T17:42:05.528517Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8a5b61ed9120d6b3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.91:2379]}","request-path":"/0/members/8a5b61ed9120d6b3/attributes","cluster-id":"9e0c1e8055c016ad","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T17:42:05.528569Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T17:42:05.528635Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:42:05.528654Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T17:42:05.529619Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T17:42:05.529656Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9e0c1e8055c016ad","local-member-id":"8a5b61ed9120d6b3","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:42:05.529708Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T17:42:05.529778Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:42:05.529815Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:42:05.532352Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-14T17:42:05.532567Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.91:2379"} {"level":"info","ts":"2026-10-14T17:42:07.32704Z","caller":"traceutil/trace.go:171","msg":"trace[1901221457] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"124.606277ms","start":"2026-10-14T17:42:07.202383Z","end":"2026-10-14T17:42:07.32699Z","steps":["trace[1901221457] 'process raft request' (duration: 124.421033ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:07.33515Z","caller":"traceutil/trace.go:171","msg":"trace[1254649163] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"132.533305ms","start":"2026-10-14T17:42:07.202602Z","end":"2026-10-14T17:42:07.335135Z","steps":["trace[1254649163] 'process raft request' (duration: 132.111234ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:07.335233Z","caller":"traceutil/trace.go:171","msg":"trace[1698105166] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"118.75724ms","start":"2026-10-14T17:42:07.216452Z","end":"2026-10-14T17:42:07.335209Z","steps":["trace[1698105166] 'read index received' (duration: 110.282398ms)","trace[1698105166] 'applied index is now lower than readState.Index' (duration: 8.473682ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:42:07.335753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.272014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-14T17:42:07.335982Z","caller":"traceutil/trace.go:171","msg":"trace[194321500] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"119.485329ms","start":"2026-10-14T17:42:07.216418Z","end":"2026-10-14T17:42:07.335904Z","steps":["trace[194321500] 'agreement among raft nodes before linearized reading' (duration: 119.221312ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:14.820102Z","caller":"traceutil/trace.go:171","msg":"trace[576802487] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"102.465372ms","start":"2026-10-14T17:42:14.717601Z","end":"2026-10-14T17:42:14.820067Z","steps":["trace[576802487] 'process raft request' (duration: 102.253237ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:15.08157Z","caller":"traceutil/trace.go:171","msg":"trace[1993835033] linearizableReadLoop","detail":"{readStateIndex:340; appliedIndex:339; }","duration":"165.231773ms","start":"2026-10-14T17:42:14.916268Z","end":"2026-10-14T17:42:15.0815Z","steps":["trace[1993835033] 'read index received' (duration: 164.843333ms)","trace[1993835033] 'applied index is now lower than readState.Index' (duration: 387.21µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:42:15.081635Z","caller":"traceutil/trace.go:171","msg":"trace[286127945] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"192.693009ms","start":"2026-10-14T17:42:14.888904Z","end":"2026-10-14T17:42:15.081597Z","steps":["trace[286127945] 'process raft request' (duration: 192.271879ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:15.081942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.849808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-10-14T17:42:15.082008Z","caller":"traceutil/trace.go:171","msg":"trace[164057146] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:326; }","duration":"115.956831ms","start":"2026-10-14T17:42:14.966035Z","end":"2026-10-14T17:42:15.081992Z","steps":["trace[164057146] 'agreement among raft nodes before linearized reading' (duration: 115.809838ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:15.081941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.619842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-10-14T17:42:15.082105Z","caller":"traceutil/trace.go:171","msg":"trace[1975192309] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:326; }","duration":"165.859488ms","start":"2026-10-14T17:42:14.916207Z","end":"2026-10-14T17:42:15.082066Z","steps":["trace[1975192309] 'agreement among raft nodes before linearized reading' (duration: 165.53027ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:20.719596Z","caller":"traceutil/trace.go:171","msg":"trace[2063881363] linearizableReadLoop","detail":"{readStateIndex:407; appliedIndex:406; }","duration":"229.272473ms","start":"2026-10-14T17:42:20.490301Z","end":"2026-10-14T17:42:20.719574Z","steps":["trace[2063881363] 'read index received' (duration: 229.018417ms)","trace[2063881363] 'applied index is now lower than readState.Index' (duration: 250.186µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:42:20.719862Z","caller":"traceutil/trace.go:171","msg":"trace[448494667] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"301.563072ms","start":"2026-10-14T17:42:20.418263Z","end":"2026-10-14T17:42:20.719826Z","steps":["trace[448494667] 'process raft request' (duration: 301.154191ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:20.719893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.569852ms","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-10-14T17:42:20.71997Z","caller":"traceutil/trace.go:171","msg":"trace[1566264301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:391; }","duration":"229.704765ms","start":"2026-10-14T17:42:20.490242Z","end":"2026-10-14T17:42:20.719947Z","steps":["trace[1566264301] 'agreement among raft nodes before linearized reading' (duration: 229.52978ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:20.720043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.753441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-14T17:42:20.720104Z","caller":"traceutil/trace.go:171","msg":"trace[2052673019] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:391; }","duration":"102.873312ms","start":"2026-10-14T17:42:20.617215Z","end":"2026-10-14T17:42:20.720088Z","steps":["trace[2052673019] 'agreement among raft nodes before linearized reading' (duration: 102.755209ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:20.720656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:42:20.418242Z","time spent":"301.705725ms","remote":"127.0.0.1:36960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5139,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:42:48.67614Z","caller":"traceutil/trace.go:171","msg":"trace[949419020] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"238.127954ms","start":"2026-10-14T17:42:48.437983Z","end":"2026-10-14T17:42:48.676111Z","steps":["trace[949419020] 'process raft request' (duration: 206.415125ms)","trace[949419020] 'compare' (duration: 31.215926ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:42:48.676594Z","caller":"traceutil/trace.go:171","msg":"trace[76674069] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"213.058443ms","start":"2026-10-14T17:42:48.463524Z","end":"2026-10-14T17:42:48.676582Z","steps":["trace[76674069] 'process raft request' (duration: 212.469978ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:51.568928Z","caller":"traceutil/trace.go:171","msg":"trace[1606273862] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"110.907902ms","start":"2026-10-14T17:42:51.457987Z","end":"2026-10-14T17:42:51.568895Z","steps":["trace[1606273862] 'process raft request' (duration: 35.531484ms)","trace[1606273862] 'compare' (duration: 75.197493ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:42:51.598784Z","caller":"traceutil/trace.go:171","msg":"trace[3031723] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"117.63749ms","start":"2026-10-14T17:42:51.481108Z","end":"2026-10-14T17:42:51.598746Z","steps":["trace[3031723] 'process raft request' (duration: 117.486536ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:51.599195Z","caller":"traceutil/trace.go:171","msg":"trace[1518738874] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"129.795157ms","start":"2026-10-14T17:42:51.469382Z","end":"2026-10-14T17:42:51.599178Z","steps":["trace[1518738874] 'process raft request' (duration: 128.961066ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:51.83102Z","caller":"traceutil/trace.go:171","msg":"trace[183328757] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"223.643798ms","start":"2026-10-14T17:42:51.607341Z","end":"2026-10-14T17:42:51.830985Z","steps":["trace[183328757] 'process raft request' (duration: 141.148431ms)","trace[183328757] 'compare' (duration: 82.332403ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:42:51.833708Z","caller":"traceutil/trace.go:171","msg":"trace[1833316254] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"225.171068ms","start":"2026-10-14T17:42:51.608495Z","end":"2026-10-14T17:42:51.833666Z","steps":["trace[1833316254] 'process raft request' (duration: 224.770107ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:51.833866Z","caller":"traceutil/trace.go:171","msg":"trace[916418330] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"194.942126ms","start":"2026-10-14T17:42:51.638874Z","end":"2026-10-14T17:42:51.833816Z","steps":["trace[916418330] 'process raft request' (duration: 194.609058ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:51.834198Z","caller":"traceutil/trace.go:171","msg":"trace[1967735189] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"165.066944ms","start":"2026-10-14T17:42:51.669101Z","end":"2026-10-14T17:42:51.834168Z","steps":["trace[1967735189] 'process raft request' (duration: 164.619993ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:57.653518Z","caller":"traceutil/trace.go:171","msg":"trace[1922344488] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"228.88661ms","start":"2026-10-14T17:42:57.424601Z","end":"2026-10-14T17:42:57.653488Z","steps":["trace[1922344488] 'read index received' (duration: 202.103486ms)","trace[1922344488] 'applied index is now lower than readState.Index' (duration: 26.781134ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:42:57.653739Z","caller":"traceutil/trace.go:171","msg":"trace[420344407] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"298.783809ms","start":"2026-10-14T17:42:57.354793Z","end":"2026-10-14T17:42:57.653577Z","steps":["trace[420344407] 'process raft request' (duration: 271.914093ms)","trace[420344407] 'compare' (duration: 26.398984ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:42:57.653887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.606504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-55wkm\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-14T17:42:57.653948Z","caller":"traceutil/trace.go:171","msg":"trace[169439816] range","detail":"{range_begin:/registry/pods/kube-system/cilium-55wkm; range_end:; response_count:1; response_revision:638; }","duration":"147.726158ms","start":"2026-10-14T17:42:57.506197Z","end":"2026-10-14T17:42:57.653923Z","steps":["trace[169439816] 'agreement among raft nodes before linearized reading' (duration: 147.448031ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:57.654021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.408644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-14T17:42:57.654156Z","caller":"traceutil/trace.go:171","msg":"trace[1293644447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"229.600719ms","start":"2026-10-14T17:42:57.424503Z","end":"2026-10-14T17:42:57.654103Z","steps":["trace[1293644447] 'agreement among raft nodes before linearized reading' (duration: 229.117916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:58.316004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.188152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:42:58.316102Z","caller":"traceutil/trace.go:171","msg":"trace[1527726694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"371.336545ms","start":"2026-10-14T17:42:57.944741Z","end":"2026-10-14T17:42:58.316078Z","steps":["trace[1527726694] 'range keys from in-memory index tree' (duration: 371.08111ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:58.316143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:42:57.944717Z","time spent":"371.417557ms","remote":"127.0.0.1:36786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T17:42:58.316003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.556222ms","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-10-14T17:42:58.316272Z","caller":"traceutil/trace.go:171","msg":"trace[1823966461] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"458.858758ms","start":"2026-10-14T17:42:57.857357Z","end":"2026-10-14T17:42:58.316216Z","steps":["trace[1823966461] 'range keys from in-memory index tree' (duration: 458.263644ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:42:58.316436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:42:57.857324Z","time spent":"459.054983ms","remote":"127.0.0.1:37046","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":"info","ts":"2026-10-14T17:42:58.319067Z","caller":"traceutil/trace.go:171","msg":"trace[52156385] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"235.480166ms","start":"2026-10-14T17:42:58.083556Z","end":"2026-10-14T17:42:58.319036Z","steps":["trace[52156385] 'process raft request' (duration: 231.101656ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:42:59.794148Z","caller":"traceutil/trace.go:171","msg":"trace[1854120814] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:695; }","duration":"114.129603ms","start":"2026-10-14T17:42:59.679959Z","end":"2026-10-14T17:42:59.794088Z","steps":["trace[1854120814] 'read index received' (duration: 87.947984ms)","trace[1854120814] 'applied index is now lower than readState.Index' (duration: 26.180379ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:42:59.794262Z","caller":"traceutil/trace.go:171","msg":"trace[1447843538] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"116.972054ms","start":"2026-10-14T17:42:59.677239Z","end":"2026-10-14T17:42:59.794211Z","steps":["trace[1447843538] 'process raft request' (duration: 90.745934ms)","trace[1447843538] 'compare' (duration: 25.953394ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:42:59.79434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.351828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:42:59.794524Z","caller":"traceutil/trace.go:171","msg":"trace[1835877857] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:668; }","duration":"114.621935ms","start":"2026-10-14T17:42:59.679886Z","end":"2026-10-14T17:42:59.794508Z","steps":["trace[1835877857] 'agreement among raft nodes before linearized reading' (duration: 114.372029ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:43:13.078509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.364051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:43:13.078638Z","caller":"traceutil/trace.go:171","msg":"trace[1121599764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"134.584477ms","start":"2026-10-14T17:43:12.944025Z","end":"2026-10-14T17:43:13.078609Z","steps":["trace[1121599764] 'range keys from in-memory index tree' (duration: 134.239329ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:43:56.984438Z","caller":"traceutil/trace.go:171","msg":"trace[1219924074] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"103.666233ms","start":"2026-10-14T17:43:56.880741Z","end":"2026-10-14T17:43:56.984407Z","steps":["trace[1219924074] 'process raft request' (duration: 103.509339ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:44:33.845928Z","caller":"traceutil/trace.go:171","msg":"trace[965881820] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"175.79751ms","start":"2026-10-14T17:44:33.670088Z","end":"2026-10-14T17:44:33.845886Z","steps":["trace[965881820] 'process raft request' (duration: 136.292264ms)","trace[965881820] 'compare' (duration: 39.207228ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:44:33.845986Z","caller":"traceutil/trace.go:171","msg":"trace[1795797398] linearizableReadLoop","detail":"{readStateIndex:1963; appliedIndex:1961; }","duration":"161.994202ms","start":"2026-10-14T17:44:33.683953Z","end":"2026-10-14T17:44:33.845948Z","steps":["trace[1795797398] 'read index received' (duration: 7.994161ms)","trace[1795797398] 'applied index is now lower than readState.Index' (duration: 153.997401ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:44:33.846228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.237829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-14T17:44:33.846345Z","caller":"traceutil/trace.go:171","msg":"trace[602665420] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1887; }","duration":"162.396803ms","start":"2026-10-14T17:44:33.683925Z","end":"2026-10-14T17:44:33.846322Z","steps":["trace[602665420] 'agreement among raft nodes before linearized reading' (duration: 162.076985ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:45:18.876314Z","caller":"traceutil/trace.go:171","msg":"trace[911979200] linearizableReadLoop","detail":"{readStateIndex:2273; appliedIndex:2272; }","duration":"156.019233ms","start":"2026-10-14T17:45:18.720265Z","end":"2026-10-14T17:45:18.876285Z","steps":["trace[911979200] 'read index received' (duration: 135.334946ms)","trace[911979200] 'applied index is now lower than readState.Index' (duration: 20.683087ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:45:18.876363Z","caller":"traceutil/trace.go:171","msg":"trace[2055028761] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"178.271829ms","start":"2026-10-14T17:45:18.698033Z","end":"2026-10-14T17:45:18.876305Z","steps":["trace[2055028761] 'process raft request' (duration: 157.628534ms)","trace[2055028761] 'compare' (duration: 20.481821ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:45:18.876475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.170976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T17:45:18.876515Z","caller":"traceutil/trace.go:171","msg":"trace[1206093944] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:2186; }","duration":"156.292459ms","start":"2026-10-14T17:45:18.72021Z","end":"2026-10-14T17:45:18.876502Z","steps":["trace[1206093944] 'agreement among raft nodes before linearized reading' (duration: 156.189767ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:45:19.258851Z","caller":"traceutil/trace.go:171","msg":"trace[425550138] linearizableReadLoop","detail":"{readStateIndex:2276; appliedIndex:2275; }","duration":"114.102836ms","start":"2026-10-14T17:45:19.144709Z","end":"2026-10-14T17:45:19.258812Z","steps":["trace[425550138] 'read index received' (duration: 63.704352ms)","trace[425550138] 'applied index is now lower than readState.Index' (duration: 50.367813ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:45:19.258891Z","caller":"traceutil/trace.go:171","msg":"trace[1574080181] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"157.352156ms","start":"2026-10-14T17:45:19.10149Z","end":"2026-10-14T17:45:19.258842Z","steps":["trace[1574080181] 'process raft request' (duration: 106.931423ms)","trace[1574080181] 'compare' (duration: 50.145816ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:45:19.259206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.476535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-14T17:45:19.259312Z","caller":"traceutil/trace.go:171","msg":"trace[900365683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2188; }","duration":"114.655149ms","start":"2026-10-14T17:45:19.144634Z","end":"2026-10-14T17:45:19.259289Z","steps":["trace[900365683] 'agreement among raft nodes before linearized reading' (duration: 114.30302ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:45:24.667347Z","caller":"traceutil/trace.go:171","msg":"trace[1619928780] linearizableReadLoop","detail":"{readStateIndex:2306; appliedIndex:2305; }","duration":"158.031804ms","start":"2026-10-14T17:45:24.509292Z","end":"2026-10-14T17:45:24.667324Z","steps":["trace[1619928780] 'read index received' (duration: 157.785668ms)","trace[1619928780] 'applied index is now lower than readState.Index' (duration: 245.176µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:45:24.667457Z","caller":"traceutil/trace.go:171","msg":"trace[732061528] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"184.18119ms","start":"2026-10-14T17:45:24.483234Z","end":"2026-10-14T17:45:24.667415Z","steps":["trace[732061528] 'process raft request' (duration: 183.911373ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:45:24.667502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.185198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T17:45:24.667546Z","caller":"traceutil/trace.go:171","msg":"trace[2121093816] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2217; }","duration":"158.25297ms","start":"2026-10-14T17:45:24.509282Z","end":"2026-10-14T17:45:24.667535Z","steps":["trace[2121093816] 'agreement among raft nodes before linearized reading' (duration: 158.137277ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:45:25.076909Z","caller":"traceutil/trace.go:171","msg":"trace[1162489088] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"164.206622ms","start":"2026-10-14T17:45:24.912669Z","end":"2026-10-14T17:45:25.076876Z","steps":["trace[1162489088] 'process raft request' (duration: 164.028447ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:45:25.194373Z","caller":"traceutil/trace.go:171","msg":"trace[374615763] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"203.603824ms","start":"2026-10-14T17:45:24.99066Z","end":"2026-10-14T17:45:25.194264Z","steps":["trace[374615763] 'process raft request' (duration: 197.512209ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:46:42.798056Z","caller":"traceutil/trace.go:171","msg":"trace[1263415386] transaction","detail":"{read_only:false; response_revision:2688; number_of_response:1; }","duration":"199.687848ms","start":"2026-10-14T17:46:42.598332Z","end":"2026-10-14T17:46:42.79802Z","steps":["trace[1263415386] 'process raft request' (duration: 199.3955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:46:43.18802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.80648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:46:43.188131Z","caller":"traceutil/trace.go:171","msg":"trace[115498761] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2689; }","duration":"242.977164ms","start":"2026-10-14T17:46:42.945132Z","end":"2026-10-14T17:46:43.18811Z","steps":["trace[115498761] 'range keys from in-memory index tree' (duration: 242.695998ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:49.963335Z","caller":"traceutil/trace.go:171","msg":"trace[1501011690] transaction","detail":"{read_only:false; response_revision:3778; number_of_response:1; }","duration":"118.779131ms","start":"2026-10-14T17:49:49.844515Z","end":"2026-10-14T17:49:49.963294Z","steps":["trace[1501011690] 'process raft request' (duration: 115.702564ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:49.963406Z","caller":"traceutil/trace.go:171","msg":"trace[932842147] transaction","detail":"{read_only:false; response_revision:3779; number_of_response:1; }","duration":"115.700785ms","start":"2026-10-14T17:49:49.847669Z","end":"2026-10-14T17:49:49.96337Z","steps":["trace[932842147] 'process raft request' (duration: 115.527081ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:50:21.230254Z","caller":"traceutil/trace.go:171","msg":"trace[926714449] transaction","detail":"{read_only:false; response_revision:4013; number_of_response:1; }","duration":"294.720963ms","start":"2026-10-14T17:50:20.935504Z","end":"2026-10-14T17:50:21.230225Z","steps":["trace[926714449] 'process raft request' (duration: 294.487027ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:50:21.230315Z","caller":"traceutil/trace.go:171","msg":"trace[1079439247] linearizableReadLoop","detail":"{readStateIndex:4169; appliedIndex:4169; }","duration":"285.400777ms","start":"2026-10-14T17:50:20.944855Z","end":"2026-10-14T17:50:21.230256Z","steps":["trace[1079439247] 'read index received' (duration: 285.374337ms)","trace[1079439247] 'applied index is now lower than readState.Index' (duration: 22.26µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:50:21.230491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.610973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:50:21.230613Z","caller":"traceutil/trace.go:171","msg":"trace[1964105316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4013; }","duration":"285.830069ms","start":"2026-10-14T17:50:20.944755Z","end":"2026-10-14T17:50:21.230586Z","steps":["trace[1964105316] 'agreement among raft nodes before linearized reading' (duration: 285.623023ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:50:21.27282Z","caller":"traceutil/trace.go:171","msg":"trace[1462943056] transaction","detail":"{read_only:false; response_revision:4014; number_of_response:1; }","duration":"260.240403ms","start":"2026-10-14T17:50:21.012496Z","end":"2026-10-14T17:50:21.272736Z","steps":["trace[1462943056] 'process raft request' (duration: 259.923016ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:50:21.272919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.582967ms","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-10-14T17:50:21.273018Z","caller":"traceutil/trace.go:171","msg":"trace[1661635036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4014; }","duration":"288.73261ms","start":"2026-10-14T17:50:20.984262Z","end":"2026-10-14T17:50:21.272995Z","steps":["trace[1661635036] 'agreement among raft nodes before linearized reading' (duration: 288.387632ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:50:21.273016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.59374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-10-14T17:50:21.273092Z","caller":"traceutil/trace.go:171","msg":"trace[732339670] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4014; }","duration":"254.704033ms","start":"2026-10-14T17:50:21.018371Z","end":"2026-10-14T17:50:21.273075Z","steps":["trace[732339670] 'agreement among raft nodes before linearized reading' (duration: 254.475158ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:51:45.10547Z","caller":"traceutil/trace.go:171","msg":"trace[127911970] linearizableReadLoop","detail":"{readStateIndex:5105; appliedIndex:5104; }","duration":"189.788486ms","start":"2026-10-14T17:51:44.915659Z","end":"2026-10-14T17:51:45.105448Z","steps":["trace[127911970] 'read index received' (duration: 189.676063ms)","trace[127911970] 'applied index is now lower than readState.Index' (duration: 111.643µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:51:45.105593Z","caller":"traceutil/trace.go:171","msg":"trace[1949880738] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4909; }","duration":"221.552106ms","start":"2026-10-14T17:51:44.884025Z","end":"2026-10-14T17:51:45.105578Z","steps":["trace[1949880738] 'process raft request' (duration: 221.357211ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:45.106019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.021633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hdtn2\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-14T17:51:45.106164Z","caller":"traceutil/trace.go:171","msg":"trace[554094210] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hdtn2; range_end:; response_count:1; response_revision:4909; }","duration":"150.129056ms","start":"2026-10-14T17:51:44.95596Z","end":"2026-10-14T17:51:45.106089Z","steps":["trace[554094210] 'agreement among raft nodes before linearized reading' (duration: 149.855429ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:45.106227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.685404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-14T17:51:45.106401Z","caller":"traceutil/trace.go:171","msg":"trace[1812382841] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4909; }","duration":"145.89659ms","start":"2026-10-14T17:51:44.960458Z","end":"2026-10-14T17:51:45.106354Z","steps":["trace[1812382841] 'agreement among raft nodes before linearized reading' (duration: 145.415768ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:45.106738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.047368ms","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-10-14T17:51:45.106897Z","caller":"traceutil/trace.go:171","msg":"trace[2005865743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4909; }","duration":"191.275294ms","start":"2026-10-14T17:51:44.915589Z","end":"2026-10-14T17:51:45.106864Z","steps":["trace[2005865743] 'agreement among raft nodes before linearized reading' (duration: 190.240407ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:51:56.02507Z","caller":"traceutil/trace.go:171","msg":"trace[724680127] linearizableReadLoop","detail":"{readStateIndex:5439; appliedIndex:5438; }","duration":"155.40201ms","start":"2026-10-14T17:51:55.869644Z","end":"2026-10-14T17:51:56.025046Z","steps":["trace[724680127] 'read index received' (duration: 155.181764ms)","trace[724680127] 'applied index is now lower than readState.Index' (duration: 219.186µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:51:56.025112Z","caller":"traceutil/trace.go:171","msg":"trace[1009998228] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"328.627987ms","start":"2026-10-14T17:51:55.696447Z","end":"2026-10-14T17:51:56.025075Z","steps":["trace[1009998228] 'process raft request' (duration: 328.437863ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:56.025324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:51:55.696404Z","time spent":"328.798342ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:51:56.025362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.214957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-10-14T17:51:56.025414Z","caller":"traceutil/trace.go:171","msg":"trace[27910048] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5236; }","duration":"144.32971ms","start":"2026-10-14T17:51:55.881066Z","end":"2026-10-14T17:51:56.025396Z","steps":["trace[27910048] 'agreement among raft nodes before linearized reading' (duration: 144.140385ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:56.025444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.797199ms","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-10-14T17:51:56.02552Z","caller":"traceutil/trace.go:171","msg":"trace[1722367406] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5236; }","duration":"155.926751ms","start":"2026-10-14T17:51:55.869576Z","end":"2026-10-14T17:51:56.025503Z","steps":["trace[1722367406] 'agreement among raft nodes before linearized reading' (duration: 155.609483ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:56.026074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.915969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:763743"} {"level":"info","ts":"2026-10-14T17:51:56.026193Z","caller":"traceutil/trace.go:171","msg":"trace[248056220] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5236; }","duration":"121.035892ms","start":"2026-10-14T17:51:55.905132Z","end":"2026-10-14T17:51:56.026168Z","steps":["trace[248056220] 'agreement among raft nodes before linearized reading' (duration: 120.326034ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:52:05.630042Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2810} {"level":"info","ts":"2026-10-14T17:52:05.704946Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2810,"took":"73.061533ms","hash":464224714,"current-db-size-bytes":54804480,"current-db-size":"55 MB","current-db-size-in-use-bytes":29896704,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T17:52:05.705024Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":464224714,"revision":2810,"compact-revision":-1} {"level":"info","ts":"2026-10-14T17:52:08.922894Z","caller":"traceutil/trace.go:171","msg":"trace[919867184] transaction","detail":"{read_only:false; response_revision:5452; number_of_response:1; }","duration":"129.486415ms","start":"2026-10-14T17:52:08.793381Z","end":"2026-10-14T17:52:08.922867Z","steps":["trace[919867184] 'process raft request' (duration: 129.354982ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:52:08.922883Z","caller":"traceutil/trace.go:171","msg":"trace[701777026] transaction","detail":"{read_only:false; response_revision:5451; number_of_response:1; }","duration":"130.002019ms","start":"2026-10-14T17:52:08.792846Z","end":"2026-10-14T17:52:08.922848Z","steps":["trace[701777026] 'process raft request' (duration: 129.745622ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:52:08.923271Z","caller":"traceutil/trace.go:171","msg":"trace[1079688927] linearizableReadLoop","detail":"{readStateIndex:5664; appliedIndex:5661; }","duration":"117.769781ms","start":"2026-10-14T17:52:08.805458Z","end":"2026-10-14T17:52:08.923228Z","steps":["trace[1079688927] 'read index received' (duration: 117.257677ms)","trace[1079688927] 'applied index is now lower than readState.Index' (duration: 510.854µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:52:08.923362Z","caller":"traceutil/trace.go:171","msg":"trace[18615104] transaction","detail":"{read_only:false; response_revision:5454; number_of_response:1; }","duration":"129.780283ms","start":"2026-10-14T17:52:08.793564Z","end":"2026-10-14T17:52:08.923345Z","steps":["trace[18615104] 'process raft request' (duration: 129.531526ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:52:08.923345Z","caller":"traceutil/trace.go:171","msg":"trace[2012770187] transaction","detail":"{read_only:false; response_revision:5453; number_of_response:1; }","duration":"129.810064ms","start":"2026-10-14T17:52:08.793469Z","end":"2026-10-14T17:52:08.923279Z","steps":["trace[2012770187] 'process raft request' (duration: 129.313651ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:52:08.923543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.116052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-fca998b5-3a8b-4477-acfe-5202b269d908\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-10-14T17:52:08.923592Z","caller":"traceutil/trace.go:171","msg":"trace[692034619] range","detail":"{range_begin:/registry/persistentvolumes/pvc-fca998b5-3a8b-4477-acfe-5202b269d908; range_end:; response_count:1; response_revision:5454; }","duration":"132.235745ms","start":"2026-10-14T17:52:08.791344Z","end":"2026-10-14T17:52:08.92358Z","steps":["trace[692034619] 'agreement among raft nodes before linearized reading' (duration: 132.04312ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:52:08.929855Z","caller":"traceutil/trace.go:171","msg":"trace[2059049003] transaction","detail":"{read_only:false; response_revision:5455; number_of_response:1; }","duration":"107.050278ms","start":"2026-10-14T17:52:08.822784Z","end":"2026-10-14T17:52:08.929834Z","steps":["trace[2059049003] 'process raft request' (duration: 106.827853ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:52:08.930248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.605356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-14T17:52:08.930296Z","caller":"traceutil/trace.go:171","msg":"trace[1125384293] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5456; }","duration":"119.704938ms","start":"2026-10-14T17:52:08.810577Z","end":"2026-10-14T17:52:08.930282Z","steps":["trace[1125384293] 'agreement among raft nodes before linearized reading' (duration: 119.473122ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:52:13.402259Z","caller":"traceutil/trace.go:171","msg":"trace[1857659223] linearizableReadLoop","detail":"{readStateIndex:5732; appliedIndex:5731; }","duration":"102.067325ms","start":"2026-10-14T17:52:13.30016Z","end":"2026-10-14T17:52:13.402228Z","steps":["trace[1857659223] 'read index received' (duration: 101.853149ms)","trace[1857659223] 'applied index is now lower than readState.Index' (duration: 212.906µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:52:13.402447Z","caller":"traceutil/trace.go:171","msg":"trace[1573308618] transaction","detail":"{read_only:false; response_revision:5520; number_of_response:1; }","duration":"115.219126ms","start":"2026-10-14T17:52:13.287169Z","end":"2026-10-14T17:52:13.402388Z","steps":["trace[1573308618] 'process raft request' (duration: 114.819996ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:52:13.402531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.334401ms","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-10-14T17:52:13.402647Z","caller":"traceutil/trace.go:171","msg":"trace[1187458574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5520; }","duration":"102.498806ms","start":"2026-10-14T17:52:13.300122Z","end":"2026-10-14T17:52:13.402621Z","steps":["trace[1187458574] 'agreement among raft nodes before linearized reading' (duration: 102.242289ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:52:20.732488Z","caller":"traceutil/trace.go:171","msg":"trace[1531953115] transaction","detail":"{read_only:false; response_revision:5676; number_of_response:1; }","duration":"141.715234ms","start":"2026-10-14T17:52:20.590734Z","end":"2026-10-14T17:52:20.732449Z","steps":["trace[1531953115] 'process raft request' (duration: 141.489949ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:52:34.096948Z","caller":"traceutil/trace.go:171","msg":"trace[1711796250] linearizableReadLoop","detail":"{readStateIndex:6065; appliedIndex:6064; }","duration":"115.936654ms","start":"2026-10-14T17:52:33.980985Z","end":"2026-10-14T17:52:34.096922Z","steps":["trace[1711796250] 'read index received' (duration: 115.860192ms)","trace[1711796250] 'applied index is now lower than readState.Index' (duration: 75.212µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:52:34.097029Z","caller":"traceutil/trace.go:171","msg":"trace[1721780112] transaction","detail":"{read_only:false; response_revision:5845; number_of_response:1; }","duration":"267.150358ms","start":"2026-10-14T17:52:33.829833Z","end":"2026-10-14T17:52:34.096983Z","steps":["trace[1721780112] 'process raft request' (duration: 266.895421ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:52:34.097204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.210772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-10-14T17:52:34.09725Z","caller":"traceutil/trace.go:171","msg":"trace[2086865202] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5845; }","duration":"116.343744ms","start":"2026-10-14T17:52:33.980895Z","end":"2026-10-14T17:52:34.097238Z","steps":["trace[2086865202] 'agreement among raft nodes before linearized reading' (duration: 116.1736ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:52:34.248714Z","caller":"traceutil/trace.go:171","msg":"trace[265409992] transaction","detail":"{read_only:false; response_revision:5846; number_of_response:1; }","duration":"137.60814ms","start":"2026-10-14T17:52:34.111072Z","end":"2026-10-14T17:52:34.24868Z","steps":["trace[265409992] 'process raft request' (duration: 137.376555ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:52:34.355208Z","caller":"traceutil/trace.go:171","msg":"trace[1858440650] transaction","detail":"{read_only:false; response_revision:5847; number_of_response:1; }","duration":"161.945714ms","start":"2026-10-14T17:52:34.193202Z","end":"2026-10-14T17:52:34.355148Z","steps":["trace[1858440650] 'process raft request' (duration: 161.72165ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:52:34.378035Z","caller":"traceutil/trace.go:171","msg":"trace[1991931932] transaction","detail":"{read_only:false; response_revision:5848; number_of_response:1; }","duration":"120.829748ms","start":"2026-10-14T17:52:34.257161Z","end":"2026-10-14T17:52:34.377991Z","steps":["trace[1991931932] 'process raft request' (duration: 120.477349ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:52:34.3781Z","caller":"traceutil/trace.go:171","msg":"trace[900629688] transaction","detail":"{read_only:false; response_revision:5850; number_of_response:1; }","duration":"120.403797ms","start":"2026-10-14T17:52:34.257674Z","end":"2026-10-14T17:52:34.378078Z","steps":["trace[900629688] 'process raft request' (duration: 120.291804ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:52:34.378202Z","caller":"traceutil/trace.go:171","msg":"trace[1579308869] transaction","detail":"{read_only:false; response_revision:5849; number_of_response:1; }","duration":"120.689204ms","start":"2026-10-14T17:52:34.257463Z","end":"2026-10-14T17:52:34.378152Z","steps":["trace[1579308869] 'process raft request' (duration: 120.424247ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:52:34.378459Z","caller":"traceutil/trace.go:171","msg":"trace[1118598301] transaction","detail":"{read_only:false; response_revision:5851; number_of_response:1; }","duration":"120.336206ms","start":"2026-10-14T17:52:34.258035Z","end":"2026-10-14T17:52:34.378371Z","steps":["trace[1118598301] 'process raft request' (duration: 119.986917ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:53:20.599242Z","caller":"traceutil/trace.go:171","msg":"trace[1239570918] transaction","detail":"{read_only:false; response_revision:6239; number_of_response:1; }","duration":"125.388882ms","start":"2026-10-14T17:53:20.473825Z","end":"2026-10-14T17:53:20.599214Z","steps":["trace[1239570918] 'process raft request' (duration: 125.185537ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:53:34.635339Z","caller":"traceutil/trace.go:171","msg":"trace[5181352] transaction","detail":"{read_only:false; response_revision:6337; number_of_response:1; }","duration":"101.270043ms","start":"2026-10-14T17:53:34.534046Z","end":"2026-10-14T17:53:34.635316Z","steps":["trace[5181352] 'process raft request' (duration: 101.115459ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:53:34.690053Z","caller":"traceutil/trace.go:171","msg":"trace[1543239070] transaction","detail":"{read_only:false; response_revision:6338; number_of_response:1; }","duration":"126.171523ms","start":"2026-10-14T17:53:34.563865Z","end":"2026-10-14T17:53:34.690036Z","steps":["trace[1543239070] 'process raft request' (duration: 126.05809ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:57:05.636088Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5387} {"level":"info","ts":"2026-10-14T17:57:05.817249Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5387,"took":"179.358099ms","hash":59512039,"current-db-size-bytes":54804480,"current-db-size":"55 MB","current-db-size-in-use-bytes":25915392,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-14T17:57:05.81733Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":59512039,"revision":5387,"compact-revision":2810} {"level":"info","ts":"2026-10-14T17:57:55.200476Z","caller":"traceutil/trace.go:171","msg":"trace[1820690844] transaction","detail":"{read_only:false; response_revision:8154; number_of_response:1; }","duration":"113.386997ms","start":"2026-10-14T17:57:55.087052Z","end":"2026-10-14T17:57:55.200439Z","steps":["trace[1820690844] 'process raft request' (duration: 85.120155ms)","trace[1820690844] 'compare' (duration: 28.099348ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:57:55.463926Z","caller":"traceutil/trace.go:171","msg":"trace[1178981395] transaction","detail":"{read_only:false; response_revision:8158; number_of_response:1; }","duration":"105.534269ms","start":"2026-10-14T17:57:55.358358Z","end":"2026-10-14T17:57:55.463892Z","steps":["trace[1178981395] 'process raft request' (duration: 79.211166ms)","trace[1178981395] 'compare' (duration: 25.964584ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:57:55.464021Z","caller":"traceutil/trace.go:171","msg":"trace[946076594] transaction","detail":"{read_only:false; response_revision:8159; number_of_response:1; }","duration":"104.591505ms","start":"2026-10-14T17:57:55.359377Z","end":"2026-10-14T17:57:55.463968Z","steps":["trace[946076594] 'process raft request' (duration: 104.36286ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:38.126183Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15470886421900780000,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-14T17:58:38.549458Z","caller":"traceutil/trace.go:171","msg":"trace[2063454705] transaction","detail":"{read_only:false; response_revision:8573; number_of_response:1; }","duration":"933.423646ms","start":"2026-10-14T17:58:37.615982Z","end":"2026-10-14T17:58:38.549406Z","steps":["trace[2063454705] 'process raft request' (duration: 933.218671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:38.549631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:37.615965Z","time spent":"933.57082ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:58:38.558714Z","caller":"traceutil/trace.go:171","msg":"trace[432801049] linearizableReadLoop","detail":"{readStateIndex:8882; appliedIndex:8880; }","duration":"932.696588ms","start":"2026-10-14T17:58:37.625991Z","end":"2026-10-14T17:58:38.558688Z","steps":["trace[432801049] 'read index received' (duration: 923.207879ms)","trace[432801049] 'applied index is now lower than readState.Index' (duration: 9.487089ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:58:38.558803Z","caller":"traceutil/trace.go:171","msg":"trace[1307226947] transaction","detail":"{read_only:false; response_revision:8574; number_of_response:1; }","duration":"567.529868ms","start":"2026-10-14T17:58:37.991249Z","end":"2026-10-14T17:58:38.558779Z","steps":["trace[1307226947] 'process raft request' (duration: 567.311422ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:38.558961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.691759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-10-14T17:58:38.558968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:37.991227Z","time spent":"567.653031ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:58:38.559024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.899771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-10-14T17:58:38.558977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.941585ms","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:5"} {"level":"info","ts":"2026-10-14T17:58:38.559077Z","caller":"traceutil/trace.go:171","msg":"trace[1325790743] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8574; }","duration":"144.984043ms","start":"2026-10-14T17:58:38.414079Z","end":"2026-10-14T17:58:38.559063Z","steps":["trace[1325790743] 'agreement among raft nodes before linearized reading' (duration: 144.806188ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:38.559107Z","caller":"traceutil/trace.go:171","msg":"trace[1463615822] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:8574; }","duration":"933.111589ms","start":"2026-10-14T17:58:37.625969Z","end":"2026-10-14T17:58:38.559081Z","steps":["trace[1463615822] 'agreement among raft nodes before linearized reading' (duration: 932.856763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:38.559123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.596466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hdtn2\" ","response":"range_response_count:1 size:6526"} {"level":"warn","ts":"2026-10-14T17:58:38.559111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.763946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T17:58:38.559175Z","caller":"traceutil/trace.go:171","msg":"trace[1612171128] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hdtn2; range_end:; response_count:1; response_revision:8574; }","duration":"778.682108ms","start":"2026-10-14T17:58:37.780477Z","end":"2026-10-14T17:58:38.559159Z","steps":["trace[1612171128] 'agreement among raft nodes before linearized reading' (duration: 778.564195ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:38.559175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:37.625896Z","time spent":"933.265163ms","remote":"127.0.0.1:36324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-10-14T17:58:38.559217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:37.780453Z","time spent":"778.757001ms","remote":"127.0.0.1:36960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-hdtn2\" "} {"level":"info","ts":"2026-10-14T17:58:38.559231Z","caller":"traceutil/trace.go:171","msg":"trace[1750288792] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:8574; }","duration":"500.94419ms","start":"2026-10-14T17:58:38.058258Z","end":"2026-10-14T17:58:38.559202Z","steps":["trace[1750288792] 'agreement among raft nodes before linearized reading' (duration: 500.703534ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:38.559289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:38.058234Z","time spent":"501.042913ms","remote":"127.0.0.1:36926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":6,"response size":31,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-10-14T17:58:38.558999Z","caller":"traceutil/trace.go:171","msg":"trace[821521137] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8574; }","duration":"830.76392ms","start":"2026-10-14T17:58:37.728222Z","end":"2026-10-14T17:58:38.558986Z","steps":["trace[821521137] 'agreement among raft nodes before linearized reading' (duration: 830.595036ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:38.559686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:37.728202Z","time spent":"831.472759ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-14T17:58:49.75637Z","caller":"traceutil/trace.go:171","msg":"trace[1659098752] linearizableReadLoop","detail":"{readStateIndex:8945; appliedIndex:8944; }","duration":"180.868637ms","start":"2026-10-14T17:58:49.575457Z","end":"2026-10-14T17:58:49.756325Z","steps":["trace[1659098752] 'read index received' (duration: 180.335994ms)","trace[1659098752] 'applied index is now lower than readState.Index' (duration: 530.513µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:58:49.756588Z","caller":"traceutil/trace.go:171","msg":"trace[1427372126] transaction","detail":"{read_only:false; response_revision:8634; number_of_response:1; }","duration":"270.580943ms","start":"2026-10-14T17:58:49.485956Z","end":"2026-10-14T17:58:49.756537Z","steps":["trace[1427372126] 'process raft request' (duration: 270.057269ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:49.756772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.273357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-14T17:58:49.756874Z","caller":"traceutil/trace.go:171","msg":"trace[1059385175] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8634; }","duration":"181.413291ms","start":"2026-10-14T17:58:49.575412Z","end":"2026-10-14T17:58:49.756825Z","steps":["trace[1059385175] 'agreement among raft nodes before linearized reading' (duration: 181.073632ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:51.111157Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15470886421900780246,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-14T17:58:51.139591Z","caller":"traceutil/trace.go:171","msg":"trace[120934100] transaction","detail":"{read_only:false; response_revision:8636; number_of_response:1; }","duration":"536.740825ms","start":"2026-10-14T17:58:50.602819Z","end":"2026-10-14T17:58:51.13956Z","steps":["trace[120934100] 'process raft request' (duration: 536.55312ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:51.13975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:50.602785Z","time spent":"536.866717ms","remote":"127.0.0.1:37046","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-10-14T17:58:51.379252Z","caller":"traceutil/trace.go:171","msg":"trace[869444472] transaction","detail":"{read_only:false; response_revision:8637; number_of_response:1; }","duration":"664.27234ms","start":"2026-10-14T17:58:50.714943Z","end":"2026-10-14T17:58:51.379215Z","steps":["trace[869444472] 'process raft request' (duration: 664.003774ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:51.379439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:50.714923Z","time spent":"664.419765ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:58:51.379467Z","caller":"traceutil/trace.go:171","msg":"trace[1618701893] linearizableReadLoop","detail":"{readStateIndex:8949; appliedIndex:8946; }","duration":"769.141867ms","start":"2026-10-14T17:58:50.610279Z","end":"2026-10-14T17:58:51.379421Z","steps":["trace[1618701893] 'read index received' (duration: 529.151523ms)","trace[1618701893] 'applied index is now lower than readState.Index' (duration: 239.987384ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:58:51.379481Z","caller":"traceutil/trace.go:171","msg":"trace[355911234] transaction","detail":"{read_only:false; response_revision:8638; number_of_response:1; }","duration":"289.795695ms","start":"2026-10-14T17:58:51.089653Z","end":"2026-10-14T17:58:51.379449Z","steps":["trace[355911234] 'process raft request' (duration: 289.488748ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:51.379813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.660309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-14T17:58:51.379779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.210897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:58:51.379884Z","caller":"traceutil/trace.go:171","msg":"trace[1228123286] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8638; }","duration":"658.756562ms","start":"2026-10-14T17:58:50.721102Z","end":"2026-10-14T17:58:51.379859Z","steps":["trace[1228123286] 'agreement among raft nodes before linearized reading' (duration: 658.571487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:51.379872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.421576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-10-14T17:58:51.3799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.493828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hdtn2\" ","response":"range_response_count:1 size:6526"} {"level":"warn","ts":"2026-10-14T17:58:51.37992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:50.721091Z","time spent":"658.820273ms","remote":"127.0.0.1:37046","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":"info","ts":"2026-10-14T17:58:51.379945Z","caller":"traceutil/trace.go:171","msg":"trace[1510409357] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hdtn2; range_end:; response_count:1; response_revision:8638; }","duration":"750.619342ms","start":"2026-10-14T17:58:50.629313Z","end":"2026-10-14T17:58:51.379933Z","steps":["trace[1510409357] 'agreement among raft nodes before linearized reading' (duration: 750.318654ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:51.379955Z","caller":"traceutil/trace.go:171","msg":"trace[280069137] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8638; }","duration":"711.541769ms","start":"2026-10-14T17:58:50.668386Z","end":"2026-10-14T17:58:51.379928Z","steps":["trace[280069137] 'agreement among raft nodes before linearized reading' (duration: 711.340183ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:51.379979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.078823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-14T17:58:51.379954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.331059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T17:58:51.38Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.24501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-10-14T17:58:51.380013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:50.668375Z","time spent":"711.624651ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-14T17:58:51.380028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.027968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-14T17:58:51.380055Z","caller":"traceutil/trace.go:171","msg":"trace[720756225] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8638; }","duration":"736.322602ms","start":"2026-10-14T17:58:50.643718Z","end":"2026-10-14T17:58:51.380041Z","steps":["trace[720756225] 'agreement among raft nodes before linearized reading' (duration: 736.171259ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:51.380057Z","caller":"traceutil/trace.go:171","msg":"trace[284313690] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:8638; }","duration":"607.486163ms","start":"2026-10-14T17:58:50.772548Z","end":"2026-10-14T17:58:51.380034Z","steps":["trace[284313690] 'agreement among raft nodes before linearized reading' (duration: 607.248117ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:51.380074Z","caller":"traceutil/trace.go:171","msg":"trace[1541634351] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8638; }","duration":"231.10878ms","start":"2026-10-14T17:58:51.148952Z","end":"2026-10-14T17:58:51.38006Z","steps":["trace[1541634351] 'agreement among raft nodes before linearized reading' (duration: 230.952336ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:51.38009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:50.643708Z","time spent":"736.374343ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-14T17:58:51.380034Z","caller":"traceutil/trace.go:171","msg":"trace[113311688] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:8638; }","duration":"187.212947ms","start":"2026-10-14T17:58:51.1928Z","end":"2026-10-14T17:58:51.380013Z","steps":["trace[113311688] 'agreement among raft nodes before linearized reading' (duration: 187.101414ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:51.380106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:50.772535Z","time spent":"607.561265ms","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"warn","ts":"2026-10-14T17:58:51.380117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.169274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-10-14T17:58:51.379933Z","caller":"traceutil/trace.go:171","msg":"trace[84791849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8638; }","duration":"434.419542ms","start":"2026-10-14T17:58:50.945481Z","end":"2026-10-14T17:58:51.3799Z","steps":["trace[84791849] 'agreement among raft nodes before linearized reading' (duration: 434.157925ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:51.380151Z","caller":"traceutil/trace.go:171","msg":"trace[469907555] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8638; }","duration":"638.225976ms","start":"2026-10-14T17:58:50.741914Z","end":"2026-10-14T17:58:51.38014Z","steps":["trace[469907555] 'agreement among raft nodes before linearized reading' (duration: 638.125473ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:51.380169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.278221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-14T17:58:51.380182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:50.945455Z","time spent":"434.71885ms","remote":"127.0.0.1:36786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T17:58:51.379994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:50.629302Z","time spent":"750.681353ms","remote":"127.0.0.1:36960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-hdtn2\" "} {"level":"info","ts":"2026-10-14T17:58:51.380226Z","caller":"traceutil/trace.go:171","msg":"trace[1867516465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8638; }","duration":"386.350733ms","start":"2026-10-14T17:58:50.993858Z","end":"2026-10-14T17:58:51.380208Z","steps":["trace[1867516465] 'agreement among raft nodes before linearized reading' (duration: 386.114367ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:51.380185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:50.7419Z","time spent":"638.278247ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":596,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-10-14T17:58:51.380273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:50.993821Z","time spent":"386.440885ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-14T17:58:51.37979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.483386ms","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-10-14T17:58:51.380369Z","caller":"traceutil/trace.go:171","msg":"trace[1618772448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8638; }","duration":"770.141172ms","start":"2026-10-14T17:58:50.610214Z","end":"2026-10-14T17:58:51.380355Z","steps":["trace[1618772448] 'agreement among raft nodes before linearized reading' (duration: 769.349202ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:58:51.380402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:50.610169Z","time spent":"770.219094ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-14T17:58:51.558421Z","caller":"traceutil/trace.go:171","msg":"trace[1803712965] linearizableReadLoop","detail":"{readStateIndex:8950; appliedIndex:8949; }","duration":"171.782479ms","start":"2026-10-14T17:58:51.386611Z","end":"2026-10-14T17:58:51.558393Z","steps":["trace[1803712965] 'read index received' (duration: 337.929µs)","trace[1803712965] 'applied index is now lower than readState.Index' (duration: 171.44334ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:58:51.558661Z","caller":"traceutil/trace.go:171","msg":"trace[611114032] transaction","detail":"{read_only:false; response_revision:8639; number_of_response:1; }","duration":"172.017475ms","start":"2026-10-14T17:58:51.386585Z","end":"2026-10-14T17:58:51.558603Z","steps":["trace[611114032] 'process raft request' (duration: 86.238728ms)","trace[611114032] 'compare' (duration: 85.427539ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:58:51.55912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.483446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:24 size:207017"} {"level":"info","ts":"2026-10-14T17:58:51.559258Z","caller":"traceutil/trace.go:171","msg":"trace[632213290] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:24; response_revision:8639; }","duration":"172.694612ms","start":"2026-10-14T17:58:51.386536Z","end":"2026-10-14T17:58:51.55923Z","steps":["trace[632213290] 'agreement among raft nodes before linearized reading' (duration: 171.968484ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:51.590638Z","caller":"traceutil/trace.go:171","msg":"trace[1640142541] transaction","detail":"{read_only:false; response_revision:8640; number_of_response:1; }","duration":"203.549388ms","start":"2026-10-14T17:58:51.387057Z","end":"2026-10-14T17:58:51.590607Z","steps":["trace[1640142541] 'process raft request' (duration: 203.327562ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:51.590801Z","caller":"traceutil/trace.go:171","msg":"trace[1371350476] transaction","detail":"{read_only:false; response_revision:8641; number_of_response:1; }","duration":"203.549147ms","start":"2026-10-14T17:58:51.387234Z","end":"2026-10-14T17:58:51.590783Z","steps":["trace[1371350476] 'process raft request' (duration: 203.321891ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:51.590965Z","caller":"traceutil/trace.go:171","msg":"trace[180532737] transaction","detail":"{read_only:false; response_revision:8642; number_of_response:1; }","duration":"203.605198ms","start":"2026-10-14T17:58:51.387348Z","end":"2026-10-14T17:58:51.590953Z","steps":["trace[180532737] 'process raft request' (duration: 203.361642ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:51.591123Z","caller":"traceutil/trace.go:171","msg":"trace[1055883869] transaction","detail":"{read_only:false; response_revision:8643; number_of_response:1; }","duration":"203.720141ms","start":"2026-10-14T17:58:51.38739Z","end":"2026-10-14T17:58:51.59111Z","steps":["trace[1055883869] 'process raft request' (duration: 203.519436ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:51.591354Z","caller":"traceutil/trace.go:171","msg":"trace[970593012] transaction","detail":"{read_only:false; response_revision:8645; number_of_response:1; }","duration":"203.269271ms","start":"2026-10-14T17:58:51.38807Z","end":"2026-10-14T17:58:51.59134Z","steps":["trace[970593012] 'process raft request' (duration: 203.181009ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:51.591401Z","caller":"traceutil/trace.go:171","msg":"trace[1906767602] transaction","detail":"{read_only:false; response_revision:8644; number_of_response:1; }","duration":"203.403854ms","start":"2026-10-14T17:58:51.387981Z","end":"2026-10-14T17:58:51.591385Z","steps":["trace[1906767602] 'process raft request' (duration: 203.079726ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:52.141318Z","caller":"traceutil/trace.go:171","msg":"trace[1522036031] transaction","detail":"{read_only:false; response_revision:8646; number_of_response:1; }","duration":"292.339959ms","start":"2026-10-14T17:58:51.848946Z","end":"2026-10-14T17:58:52.141286Z","steps":["trace[1522036031] 'process raft request' (duration: 292.025941ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:52.508314Z","caller":"traceutil/trace.go:171","msg":"trace[1461569283] transaction","detail":"{read_only:false; response_revision:8647; number_of_response:1; }","duration":"321.346869ms","start":"2026-10-14T17:58:52.186928Z","end":"2026-10-14T17:58:52.508275Z","steps":["trace[1461569283] 'process raft request' (duration: 265.927516ms)","trace[1461569283] 'compare' (duration: 55.203717ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:58:52.5085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:58:52.186899Z","time spent":"321.503063ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:58:52.80335Z","caller":"traceutil/trace.go:171","msg":"trace[246910873] transaction","detail":"{read_only:false; response_revision:8648; number_of_response:1; }","duration":"205.083106ms","start":"2026-10-14T17:58:52.598217Z","end":"2026-10-14T17:58:52.803301Z","steps":["trace[246910873] 'process raft request' (duration: 204.706867ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:59:21.569895Z","caller":"traceutil/trace.go:171","msg":"trace[2001979992] transaction","detail":"{read_only:false; response_revision:8852; number_of_response:1; }","duration":"296.72355ms","start":"2026-10-14T17:59:21.273144Z","end":"2026-10-14T17:59:21.569867Z","steps":["trace[2001979992] 'process raft request' (duration: 296.465224ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:59:21.709457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.127955ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:59:21.70964Z","caller":"traceutil/trace.go:171","msg":"trace[173025345] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8852; }","duration":"117.359181ms","start":"2026-10-14T17:59:21.592256Z","end":"2026-10-14T17:59:21.709615Z","steps":["trace[173025345] 'range keys from in-memory index tree' (duration: 117.102265ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:59:21.709796Z","caller":"traceutil/trace.go:171","msg":"trace[352071993] transaction","detail":"{read_only:false; response_revision:8853; number_of_response:1; }","duration":"189.568826ms","start":"2026-10-14T17:59:21.52018Z","end":"2026-10-14T17:59:21.709749Z","steps":["trace[352071993] 'process raft request' (duration: 91.680285ms)","trace[352071993] 'compare' (duration: 97.717527ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:59:35.759486Z","caller":"traceutil/trace.go:171","msg":"trace[1678413641] transaction","detail":"{read_only:false; response_revision:8967; number_of_response:1; }","duration":"124.362287ms","start":"2026-10-14T17:59:35.635071Z","end":"2026-10-14T17:59:35.759433Z","steps":["trace[1678413641] 'process raft request' (duration: 124.173102ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:59:36.003955Z","caller":"traceutil/trace.go:171","msg":"trace[951971915] linearizableReadLoop","detail":"{readStateIndex:9293; appliedIndex:9292; }","duration":"158.944987ms","start":"2026-10-14T17:59:35.844982Z","end":"2026-10-14T17:59:36.003927Z","steps":["trace[951971915] 'read index received' (duration: 158.735932ms)","trace[951971915] 'applied index is now lower than readState.Index' (duration: 207.635µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:59:36.004138Z","caller":"traceutil/trace.go:171","msg":"trace[999595651] transaction","detail":"{read_only:false; response_revision:8968; number_of_response:1; }","duration":"238.152498ms","start":"2026-10-14T17:59:35.765915Z","end":"2026-10-14T17:59:36.004067Z","steps":["trace[999595651] 'process raft request' (duration: 237.82566ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:59:36.00423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.222234ms","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-10-14T17:59:36.004277Z","caller":"traceutil/trace.go:171","msg":"trace[1251047869] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8968; }","duration":"159.340086ms","start":"2026-10-14T17:59:35.844917Z","end":"2026-10-14T17:59:36.004258Z","steps":["trace[1251047869] 'agreement among raft nodes before linearized reading' (duration: 159.173302ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:59:36.004525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.979622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hdtn2\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-14T17:59:36.004636Z","caller":"traceutil/trace.go:171","msg":"trace[1077237694] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hdtn2; range_end:; response_count:1; response_revision:8968; }","duration":"123.184677ms","start":"2026-10-14T17:59:35.881424Z","end":"2026-10-14T17:59:36.004609Z","steps":["trace[1077237694] 'agreement among raft nodes before linearized reading' (duration: 122.863018ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:59:36.005882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.289893ms","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-10-14T17:59:36.006118Z","caller":"traceutil/trace.go:171","msg":"trace[722671194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8968; }","duration":"102.64462ms","start":"2026-10-14T17:59:35.903447Z","end":"2026-10-14T17:59:36.006091Z","steps":["trace[722671194] 'agreement among raft nodes before linearized reading' (duration: 102.21475ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:59:36.455328Z","caller":"traceutil/trace.go:171","msg":"trace[657131329] transaction","detail":"{read_only:false; response_revision:8978; number_of_response:1; }","duration":"147.121409ms","start":"2026-10-14T17:59:36.308175Z","end":"2026-10-14T17:59:36.455297Z","steps":["trace[657131329] 'process raft request' (duration: 146.915603ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:59:36.829274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.189499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:59:36.829369Z","caller":"traceutil/trace.go:171","msg":"trace[266602129] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8978; }","duration":"246.378244ms","start":"2026-10-14T17:59:36.582966Z","end":"2026-10-14T17:59:36.829344Z","steps":["trace[266602129] 'count revisions from in-memory index tree' (duration: 246.063906ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:59:37.30474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.269391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-10-14T17:59:37.304899Z","caller":"traceutil/trace.go:171","msg":"trace[1674229327] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:8982; }","duration":"129.474585ms","start":"2026-10-14T17:59:37.175386Z","end":"2026-10-14T17:59:37.304861Z","steps":["trace[1674229327] 'range keys from in-memory index tree' (duration: 128.923691ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:59:51.182302Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024e7.wal"} {"level":"info","ts":"2026-10-14T18:00:52.618602Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8a5b61ed9120d6b3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"warn","ts":"2026-10-14T18:00:52.705433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.115271ms","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-10-14T18:00:52.705526Z","caller":"traceutil/trace.go:171","msg":"trace[1587601240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9657; }","duration":"134.245065ms","start":"2026-10-14T18:00:52.571259Z","end":"2026-10-14T18:00:52.705504Z","steps":["trace[1587601240] 'agreement among raft nodes before linearized reading' (duration: 47.675959ms)","trace[1587601240] 'range keys from in-memory index tree' (duration: 86.32675ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:00:52.765843Z","caller":"traceutil/trace.go:171","msg":"trace[1548971123] linearizableReadLoop","detail":"{readStateIndex:10002; appliedIndex:10001; }","duration":"147.120868ms","start":"2026-10-14T18:00:52.618697Z","end":"2026-10-14T18:00:52.765818Z","steps":["trace[1548971123] 'read index received' (duration: 146.758059ms)","trace[1548971123] 'applied index is now lower than readState.Index' (duration: 362.089µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:00:52.765989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.051488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-14T18:00:52.766013Z","caller":"traceutil/trace.go:171","msg":"trace[2044519497] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9658; }","duration":"194.104669ms","start":"2026-10-14T18:00:52.571899Z","end":"2026-10-14T18:00:52.766004Z","steps":["trace[2044519497] 'agreement among raft nodes before linearized reading' (duration: 193.987916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:00:52.766102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.386735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hdtn2\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-14T18:00:52.766235Z","caller":"traceutil/trace.go:171","msg":"trace[403641356] transaction","detail":"{read_only:false; response_revision:9658; number_of_response:1; }","duration":"177.004331ms","start":"2026-10-14T18:00:52.589224Z","end":"2026-10-14T18:00:52.766228Z","steps":["trace[403641356] 'process raft request' (duration: 176.260342ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:00:52.766238Z","caller":"traceutil/trace.go:171","msg":"trace[1869094577] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hdtn2; range_end:; response_count:1; response_revision:9658; }","duration":"174.561069ms","start":"2026-10-14T18:00:52.591653Z","end":"2026-10-14T18:00:52.766214Z","steps":["trace[1869094577] 'agreement among raft nodes before linearized reading' (duration: 174.20319ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:00:52.766291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.078872ms","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-10-14T18:00:52.766363Z","caller":"traceutil/trace.go:171","msg":"trace[1132580192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9658; }","duration":"183.167345ms","start":"2026-10-14T18:00:52.583179Z","end":"2026-10-14T18:00:52.766346Z","steps":["trace[1132580192] 'agreement among raft nodes before linearized reading' (duration: 183.015801ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:00:52.76639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.915996ms","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-10-14T18:00:52.766503Z","caller":"traceutil/trace.go:171","msg":"trace[777953242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9658; }","duration":"194.044789ms","start":"2026-10-14T18:00:52.572434Z","end":"2026-10-14T18:00:52.766479Z","steps":["trace[777953242] 'agreement among raft nodes before linearized reading' (duration: 193.664369ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:00:52.766107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.490574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-10-14T18:00:52.766709Z","caller":"traceutil/trace.go:171","msg":"trace[705314377] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:9658; }","duration":"186.145931ms","start":"2026-10-14T18:00:52.580548Z","end":"2026-10-14T18:00:52.766694Z","steps":["trace[705314377] 'agreement among raft nodes before linearized reading' (duration: 185.382501ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:00:52.928445Z","caller":"traceutil/trace.go:171","msg":"trace[1885234790] transaction","detail":"{read_only:false; response_revision:9660; number_of_response:1; }","duration":"157.548661ms","start":"2026-10-14T18:00:52.770876Z","end":"2026-10-14T18:00:52.928425Z","steps":["trace[1885234790] 'process raft request' (duration: 157.297865ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:00:52.928649Z","caller":"traceutil/trace.go:171","msg":"trace[354625066] transaction","detail":"{read_only:false; response_revision:9661; number_of_response:1; }","duration":"157.676465ms","start":"2026-10-14T18:00:52.770963Z","end":"2026-10-14T18:00:52.928639Z","steps":["trace[354625066] 'process raft request' (duration: 157.407698ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:00:52.929041Z","caller":"traceutil/trace.go:171","msg":"trace[1372702641] linearizableReadLoop","detail":"{readStateIndex:10005; appliedIndex:10002; }","duration":"157.8592ms","start":"2026-10-14T18:00:52.771159Z","end":"2026-10-14T18:00:52.929018Z","steps":["trace[1372702641] 'read index received' (duration: 25.874861ms)","trace[1372702641] 'applied index is now lower than readState.Index' (duration: 131.983359ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:00:52.929058Z","caller":"traceutil/trace.go:171","msg":"trace[1244823469] transaction","detail":"{read_only:false; response_revision:9662; number_of_response:1; }","duration":"157.48583ms","start":"2026-10-14T18:00:52.771542Z","end":"2026-10-14T18:00:52.929028Z","steps":["trace[1244823469] 'process raft request' (duration: 157.063379ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:00:52.929207Z","caller":"traceutil/trace.go:171","msg":"trace[959841727] transaction","detail":"{read_only:false; response_revision:9663; number_of_response:1; }","duration":"157.008608ms","start":"2026-10-14T18:00:52.772179Z","end":"2026-10-14T18:00:52.929187Z","steps":["trace[959841727] 'process raft request' (duration: 156.745841ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:00:52.929448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.2801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:28 size:244624"} {"level":"info","ts":"2026-10-14T18:00:52.92949Z","caller":"traceutil/trace.go:171","msg":"trace[1751970888] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:28; response_revision:9663; }","duration":"158.337211ms","start":"2026-10-14T18:00:52.771141Z","end":"2026-10-14T18:00:52.929478Z","steps":["trace[1751970888] 'agreement among raft nodes before linearized reading' (duration: 157.966392ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:00:52.990086Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-14T18:00:52.990259Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-14T18:01:32.631247Z","caller":"traceutil/trace.go:171","msg":"trace[1687791] transaction","detail":"{read_only:false; response_revision:10030; number_of_response:1; }","duration":"124.417591ms","start":"2026-10-14T18:01:32.506506Z","end":"2026-10-14T18:01:32.630924Z","steps":["trace[1687791] 'process raft request' (duration: 124.146665ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:02:05.643246Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7808} {"level":"info","ts":"2026-10-14T18:02:05.719152Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7808,"took":"73.78741ms","hash":37891125,"current-db-size-bytes":54804480,"current-db-size":"55 MB","current-db-size-in-use-bytes":27820032,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T18:02:05.719446Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":37891125,"revision":7808,"compact-revision":5387} {"level":"info","ts":"2026-10-14T18:03:15.641669Z","caller":"traceutil/trace.go:171","msg":"trace[1312936708] linearizableReadLoop","detail":"{readStateIndex:11210; appliedIndex:11209; }","duration":"100.244425ms","start":"2026-10-14T18:03:15.5414Z","end":"2026-10-14T18:03:15.641645Z","steps":["trace[1312936708] 'read index received' (duration: 99.998649ms)","trace[1312936708] 'applied index is now lower than readState.Index' (duration: 244.886µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:03:15.641795Z","caller":"traceutil/trace.go:171","msg":"trace[199447169] transaction","detail":"{read_only:false; response_revision:10829; number_of_response:1; }","duration":"104.601766ms","start":"2026-10-14T18:03:15.537147Z","end":"2026-10-14T18:03:15.641748Z","steps":["trace[199447169] 'process raft request' (duration: 104.331289ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:03:15.641908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.495512ms","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-10-14T18:03:15.641948Z","caller":"traceutil/trace.go:171","msg":"trace[1646298144] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10829; }","duration":"100.597454ms","start":"2026-10-14T18:03:15.541339Z","end":"2026-10-14T18:03:15.641937Z","steps":["trace[1646298144] 'agreement among raft nodes before linearized reading' (duration: 100.408599ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:37.218369Z","caller":"traceutil/trace.go:171","msg":"trace[535994525] linearizableReadLoop","detail":"{readStateIndex:12004; appliedIndex:12003; }","duration":"126.783401ms","start":"2026-10-14T18:04:37.091552Z","end":"2026-10-14T18:04:37.218335Z","steps":["trace[535994525] 'read index received' (duration: 126.437352ms)","trace[535994525] 'applied index is now lower than readState.Index' (duration: 344.399µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:04:37.218471Z","caller":"traceutil/trace.go:171","msg":"trace[755197605] transaction","detail":"{read_only:false; response_revision:11605; number_of_response:1; }","duration":"127.007687ms","start":"2026-10-14T18:04:37.091419Z","end":"2026-10-14T18:04:37.218427Z","steps":["trace[755197605] 'process raft request' (duration: 126.654778ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:37.218622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.997007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"warn","ts":"2026-10-14T18:04:37.21862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.635158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T18:04:37.218602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.007867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T18:04:37.218647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.977816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:04:37.218668Z","caller":"traceutil/trace.go:171","msg":"trace[2007402535] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11605; }","duration":"127.066568ms","start":"2026-10-14T18:04:37.091592Z","end":"2026-10-14T18:04:37.218658Z","steps":["trace[2007402535] 'agreement among raft nodes before linearized reading' (duration: 126.961096ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:37.218685Z","caller":"traceutil/trace.go:171","msg":"trace[1205461580] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11605; }","duration":"126.73128ms","start":"2026-10-14T18:04:37.091936Z","end":"2026-10-14T18:04:37.218668Z","steps":["trace[1205461580] 'agreement among raft nodes before linearized reading' (duration: 126.625398ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:37.218724Z","caller":"traceutil/trace.go:171","msg":"trace[343077415] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11605; }","duration":"127.068619ms","start":"2026-10-14T18:04:37.09162Z","end":"2026-10-14T18:04:37.218689Z","steps":["trace[343077415] 'agreement among raft nodes before linearized reading' (duration: 126.974047ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:37.218723Z","caller":"traceutil/trace.go:171","msg":"trace[1418013922] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11605; }","duration":"127.170431ms","start":"2026-10-14T18:04:37.091512Z","end":"2026-10-14T18:04:37.218682Z","steps":["trace[1418013922] 'agreement among raft nodes before linearized reading' (duration: 126.962876ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:37.218789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.594567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:04:37.218877Z","caller":"traceutil/trace.go:171","msg":"trace[520356892] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11605; }","duration":"126.7526ms","start":"2026-10-14T18:04:37.0921Z","end":"2026-10-14T18:04:37.218852Z","steps":["trace[520356892] 'agreement among raft nodes before linearized reading' (duration: 126.551345ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:07:05.653663Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10254} {"level":"info","ts":"2026-10-14T18:07:05.741428Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10254,"took":"84.536886ms","hash":4237374842,"current-db-size-bytes":54804480,"current-db-size":"55 MB","current-db-size-in-use-bytes":28131328,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T18:07:05.74151Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4237374842,"revision":10254,"compact-revision":7808} {"level":"info","ts":"2026-10-14T18:10:10.327896Z","caller":"traceutil/trace.go:171","msg":"trace[1466929872] transaction","detail":"{read_only:false; response_revision:13900; number_of_response:1; }","duration":"103.531509ms","start":"2026-10-14T18:10:10.224332Z","end":"2026-10-14T18:10:10.327864Z","steps":["trace[1466929872] 'process raft request' (duration: 103.322613ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:10:10.340235Z","caller":"traceutil/trace.go:171","msg":"trace[1690441567] linearizableReadLoop","detail":"{readStateIndex:14376; appliedIndex:14374; }","duration":"111.754765ms","start":"2026-10-14T18:10:10.228457Z","end":"2026-10-14T18:10:10.340212Z","steps":["trace[1690441567] 'read index received' (duration: 99.176469ms)","trace[1690441567] 'applied index is now lower than readState.Index' (duration: 12.577506ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:10:10.340543Z","caller":"traceutil/trace.go:171","msg":"trace[890116248] transaction","detail":"{read_only:false; response_revision:13901; number_of_response:1; }","duration":"113.624853ms","start":"2026-10-14T18:10:10.226903Z","end":"2026-10-14T18:10:10.340528Z","steps":["trace[890116248] 'process raft request' (duration: 113.191852ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:10:10.340637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.891679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:10:10.340717Z","caller":"traceutil/trace.go:171","msg":"trace[401390973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13901; }","duration":"110.082413ms","start":"2026-10-14T18:10:10.230616Z","end":"2026-10-14T18:10:10.340698Z","steps":["trace[401390973] 'agreement among raft nodes before linearized reading' (duration: 109.94026ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:10:10.340771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.290069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-14T18:10:10.340848Z","caller":"traceutil/trace.go:171","msg":"trace[2032532845] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13901; }","duration":"112.416163ms","start":"2026-10-14T18:10:10.228412Z","end":"2026-10-14T18:10:10.340828Z","steps":["trace[2032532845] 'agreement among raft nodes before linearized reading' (duration: 112.179687ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:10:27.115521Z","caller":"traceutil/trace.go:171","msg":"trace[68995096] transaction","detail":"{read_only:false; response_revision:14030; number_of_response:1; }","duration":"160.949862ms","start":"2026-10-14T18:10:26.95453Z","end":"2026-10-14T18:10:27.11548Z","steps":["trace[68995096] 'process raft request' (duration: 160.702765ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:10:28.097148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.596656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:10:28.09726Z","caller":"traceutil/trace.go:171","msg":"trace[1146523074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14031; }","duration":"155.744509ms","start":"2026-10-14T18:10:27.941492Z","end":"2026-10-14T18:10:28.097237Z","steps":["trace[1146523074] 'range keys from in-memory index tree' (duration: 155.529424ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:10:28.097333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.728389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-14T18:10:28.097398Z","caller":"traceutil/trace.go:171","msg":"trace[2104033283] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14031; }","duration":"208.872383ms","start":"2026-10-14T18:10:27.888514Z","end":"2026-10-14T18:10:28.097386Z","steps":["trace[2104033283] 'range keys from in-memory index tree' (duration: 208.551115ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:10:31.308205Z","caller":"traceutil/trace.go:171","msg":"trace[682712691] transaction","detail":"{read_only:false; response_revision:14049; number_of_response:1; }","duration":"179.627789ms","start":"2026-10-14T18:10:31.128511Z","end":"2026-10-14T18:10:31.308139Z","steps":["trace[682712691] 'process raft request' (duration: 179.351692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:10:53.001578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.227086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:10:53.001755Z","caller":"traceutil/trace.go:171","msg":"trace[210646536] linearizableReadLoop","detail":"{readStateIndex:14654; appliedIndex:14653; }","duration":"276.782619ms","start":"2026-10-14T18:10:52.724945Z","end":"2026-10-14T18:10:53.001728Z","steps":["trace[210646536] 'read index received' (duration: 175.848335ms)","trace[210646536] 'applied index is now lower than readState.Index' (duration: 100.931824ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:10:53.00189Z","caller":"traceutil/trace.go:171","msg":"trace[1599341326] transaction","detail":"{read_only:false; response_revision:14167; number_of_response:1; }","duration":"371.334472ms","start":"2026-10-14T18:10:52.630513Z","end":"2026-10-14T18:10:53.001847Z","steps":["trace[1599341326] 'process raft request' (duration: 270.304765ms)","trace[1599341326] 'compare' (duration: 99.371315ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:10:53.001949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.994004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T18:10:53.002007Z","caller":"traceutil/trace.go:171","msg":"trace[375478924] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14167; }","duration":"277.080806ms","start":"2026-10-14T18:10:52.724904Z","end":"2026-10-14T18:10:53.001985Z","steps":["trace[375478924] 'agreement among raft nodes before linearized reading' (duration: 276.965793ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:10:53.002082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:10:52.630487Z","time spent":"371.484366ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:11:09.568792Z","caller":"traceutil/trace.go:171","msg":"trace[2035608758] transaction","detail":"{read_only:false; response_revision:14269; number_of_response:1; }","duration":"101.722793ms","start":"2026-10-14T18:11:09.467038Z","end":"2026-10-14T18:11:09.56876Z","steps":["trace[2035608758] 'process raft request' (duration: 101.100668ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:11:10.656619Z","caller":"traceutil/trace.go:171","msg":"trace[1836420408] transaction","detail":"{read_only:false; response_revision:14273; number_of_response:1; }","duration":"121.140391ms","start":"2026-10-14T18:11:10.535443Z","end":"2026-10-14T18:11:10.656583Z","steps":["trace[1836420408] 'process raft request' (duration: 120.821973ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:11:34.482832Z","caller":"traceutil/trace.go:171","msg":"trace[2095703790] linearizableReadLoop","detail":"{readStateIndex:15094; appliedIndex:15093; }","duration":"228.233719ms","start":"2026-10-14T18:11:34.254571Z","end":"2026-10-14T18:11:34.482805Z","steps":["trace[2095703790] 'read index received' (duration: 227.973912ms)","trace[2095703790] 'applied index is now lower than readState.Index' (duration: 258.047µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:11:34.482888Z","caller":"traceutil/trace.go:171","msg":"trace[1279674174] transaction","detail":"{read_only:false; response_revision:14587; number_of_response:1; }","duration":"277.620319ms","start":"2026-10-14T18:11:34.205238Z","end":"2026-10-14T18:11:34.482858Z","steps":["trace[1279674174] 'process raft request' (duration: 277.373873ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:11:34.483025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.423674ms","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":"warn","ts":"2026-10-14T18:11:34.483029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.339175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:11:34.483066Z","caller":"traceutil/trace.go:171","msg":"trace[339262175] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:14587; }","duration":"228.537317ms","start":"2026-10-14T18:11:34.254517Z","end":"2026-10-14T18:11:34.483055Z","steps":["trace[339262175] 'agreement among raft nodes before linearized reading' (duration: 228.437085ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:11:34.483085Z","caller":"traceutil/trace.go:171","msg":"trace[775993410] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:14587; }","duration":"211.442428ms","start":"2026-10-14T18:11:34.271626Z","end":"2026-10-14T18:11:34.483069Z","steps":["trace[775993410] 'agreement among raft nodes before linearized reading' (duration: 211.343025ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:12:05.660515Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12665} {"level":"info","ts":"2026-10-14T18:12:05.751162Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12665,"took":"87.763973ms","hash":1542515366,"current-db-size-bytes":54804480,"current-db-size":"55 MB","current-db-size-in-use-bytes":28585984,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-14T18:12:05.75124Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1542515366,"revision":12665,"compact-revision":10254} {"level":"warn","ts":"2026-10-14T18:13:01.780598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.506226ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:13:01.780726Z","caller":"traceutil/trace.go:171","msg":"trace[764626720] linearizableReadLoop","detail":"{readStateIndex:15940; appliedIndex:15939; }","duration":"246.807276ms","start":"2026-10-14T18:13:01.533901Z","end":"2026-10-14T18:13:01.780708Z","steps":["trace[764626720] 'read index received' (duration: 108.147076ms)","trace[764626720] 'applied index is now lower than readState.Index' (duration: 138.65866ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:13:01.780776Z","caller":"traceutil/trace.go:171","msg":"trace[1845994184] transaction","detail":"{read_only:false; response_revision:15409; number_of_response:1; }","duration":"265.369842ms","start":"2026-10-14T18:13:01.515371Z","end":"2026-10-14T18:13:01.780741Z","steps":["trace[1845994184] 'process raft request' (duration: 126.622129ms)","trace[1845994184] 'compare' (duration: 137.82557ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:13:01.780991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.788087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-hxr7v\" ","response":"range_response_count:1 size:20655"} {"level":"warn","ts":"2026-10-14T18:13:01.780978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.046402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-14T18:13:01.781096Z","caller":"traceutil/trace.go:171","msg":"trace[612251557] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15409; }","duration":"247.210196ms","start":"2026-10-14T18:13:01.533856Z","end":"2026-10-14T18:13:01.781066Z","steps":["trace[612251557] 'agreement among raft nodes before linearized reading' (duration: 246.901488ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:13:01.781029Z","caller":"traceutil/trace.go:171","msg":"trace[1568209692] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-hxr7v; range_end:; response_count:1; response_revision:15409; }","duration":"219.822708ms","start":"2026-10-14T18:13:01.561194Z","end":"2026-10-14T18:13:01.781016Z","steps":["trace[1568209692] 'agreement among raft nodes before linearized reading' (duration: 219.632063ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:01.781316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.966195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:13:01.781348Z","caller":"traceutil/trace.go:171","msg":"trace[1833231285] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15409; }","duration":"109.011757ms","start":"2026-10-14T18:13:01.672328Z","end":"2026-10-14T18:13:01.78134Z","steps":["trace[1833231285] 'agreement among raft nodes before linearized reading' (duration: 108.971386ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:01.781282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.785441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:13:01.781489Z","caller":"traceutil/trace.go:171","msg":"trace[410748149] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15409; }","duration":"109.014796ms","start":"2026-10-14T18:13:01.672461Z","end":"2026-10-14T18:13:01.781475Z","steps":["trace[410748149] 'agreement among raft nodes before linearized reading' (duration: 108.794941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:01.78158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.411427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:13:01.781613Z","caller":"traceutil/trace.go:171","msg":"trace[1792853278] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15409; }","duration":"109.444278ms","start":"2026-10-14T18:13:01.67216Z","end":"2026-10-14T18:13:01.781604Z","steps":["trace[1792853278] 'agreement among raft nodes before linearized reading' (duration: 109.397796ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:01.781878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.906205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-14T18:13:01.781924Z","caller":"traceutil/trace.go:171","msg":"trace[576742511] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15409; }","duration":"123.003847ms","start":"2026-10-14T18:13:01.658905Z","end":"2026-10-14T18:13:01.781909Z","steps":["trace[576742511] 'agreement among raft nodes before linearized reading' (duration: 122.892764ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:01.781966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.07727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-14T18:13:01.782024Z","caller":"traceutil/trace.go:171","msg":"trace[1663027354] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15409; }","duration":"121.188333ms","start":"2026-10-14T18:13:01.66082Z","end":"2026-10-14T18:13:01.782008Z","steps":["trace[1663027354] 'agreement among raft nodes before linearized reading' (duration: 121.06423ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:01.782093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.45224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:13:01.78217Z","caller":"traceutil/trace.go:171","msg":"trace[1125630400] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15409; }","duration":"121.544052ms","start":"2026-10-14T18:13:01.660575Z","end":"2026-10-14T18:13:01.782119Z","steps":["trace[1125630400] 'agreement among raft nodes before linearized reading' (duration: 121.49318ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:01.782415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.636389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-14T18:13:01.782522Z","caller":"traceutil/trace.go:171","msg":"trace[2022545793] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15409; }","duration":"118.748421ms","start":"2026-10-14T18:13:01.663756Z","end":"2026-10-14T18:13:01.782505Z","steps":["trace[2022545793] 'agreement among raft nodes before linearized reading' (duration: 118.245989ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:02.259044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.414908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:13:02.259405Z","caller":"traceutil/trace.go:171","msg":"trace[153157182] transaction","detail":"{read_only:false; response_revision:15412; number_of_response:1; }","duration":"208.132884ms","start":"2026-10-14T18:13:02.051248Z","end":"2026-10-14T18:13:02.25938Z","steps":["trace[153157182] 'process raft request' (duration: 208.061092ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:13:02.259529Z","caller":"traceutil/trace.go:171","msg":"trace[1203906945] transaction","detail":"{read_only:false; response_revision:15410; number_of_response:1; }","duration":"471.493555ms","start":"2026-10-14T18:13:01.788015Z","end":"2026-10-14T18:13:02.259508Z","steps":["trace[1203906945] 'process raft request' (duration: 200.493633ms)","trace[1203906945] 'compare' (duration: 270.011618ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:13:02.259639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:13:01.787986Z","time spent":"471.599758ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:13:02.259658Z","caller":"traceutil/trace.go:171","msg":"trace[1590261538] transaction","detail":"{read_only:false; response_revision:15411; number_of_response:1; }","duration":"468.966353ms","start":"2026-10-14T18:13:01.790673Z","end":"2026-10-14T18:13:02.25964Z","steps":["trace[1590261538] 'process raft request' (duration: 468.512371ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:02.259745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:13:01.790577Z","time spent":"469.120516ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:13:02.259866Z","caller":"traceutil/trace.go:171","msg":"trace[1558420193] linearizableReadLoop","detail":"{readStateIndex:15942; appliedIndex:15940; }","duration":"467.122116ms","start":"2026-10-14T18:13:01.792728Z","end":"2026-10-14T18:13:02.25985Z","steps":["trace[1558420193] 'read index received' (duration: 195.793685ms)","trace[1558420193] 'applied index is now lower than readState.Index' (duration: 271.326631ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:13:02.260282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.911596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T18:13:02.26033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.394209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hdtn2\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-14T18:13:02.260359Z","caller":"traceutil/trace.go:171","msg":"trace[262653002] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15412; }","duration":"368.016849ms","start":"2026-10-14T18:13:01.892324Z","end":"2026-10-14T18:13:02.260341Z","steps":["trace[262653002] 'agreement among raft nodes before linearized reading' (duration: 367.836184ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:02.260316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.917766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T18:13:02.260407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:13:01.892319Z","time spent":"368.07914ms","remote":"127.0.0.1:37024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-10-14T18:13:02.260399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.035786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-14T18:13:02.260406Z","caller":"traceutil/trace.go:171","msg":"trace[1065395806] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hdtn2; range_end:; response_count:1; response_revision:15412; }","duration":"372.522782ms","start":"2026-10-14T18:13:01.88786Z","end":"2026-10-14T18:13:02.260382Z","steps":["trace[1065395806] 'agreement among raft nodes before linearized reading' (duration: 372.274375ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:02.260403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.944001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:13:02.260466Z","caller":"traceutil/trace.go:171","msg":"trace[60430284] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15412; }","duration":"293.103167ms","start":"2026-10-14T18:13:01.967346Z","end":"2026-10-14T18:13:02.260449Z","steps":["trace[60430284] 'agreement among raft nodes before linearized reading' (duration: 292.81908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:02.260466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.132796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-14T18:13:02.260528Z","caller":"traceutil/trace.go:171","msg":"trace[689749012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15412; }","duration":"316.133166ms","start":"2026-10-14T18:13:01.944368Z","end":"2026-10-14T18:13:02.260501Z","steps":["trace[689749012] 'agreement among raft nodes before linearized reading' (duration: 315.931201ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:13:02.260583Z","caller":"traceutil/trace.go:171","msg":"trace[196563392] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15412; }","duration":"367.26588ms","start":"2026-10-14T18:13:01.893295Z","end":"2026-10-14T18:13:02.260561Z","steps":["trace[196563392] 'agreement among raft nodes before linearized reading' (duration: 366.85869ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:02.260597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:13:01.944341Z","time spent":"316.241519ms","remote":"127.0.0.1:36786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T18:13:02.260631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:13:01.893286Z","time spent":"367.334152ms","remote":"127.0.0.1:37024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5547,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-10-14T18:13:02.260481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:13:01.887835Z","time spent":"372.633925ms","remote":"127.0.0.1:36960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-hdtn2\" "} {"level":"warn","ts":"2026-10-14T18:13:02.26034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.016439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:13:02.260842Z","caller":"traceutil/trace.go:171","msg":"trace[316644743] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15412; }","duration":"368.562383ms","start":"2026-10-14T18:13:01.892251Z","end":"2026-10-14T18:13:02.260813Z","steps":["trace[316644743] 'agreement among raft nodes before linearized reading' (duration: 368.001168ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:13:02.260427Z","caller":"traceutil/trace.go:171","msg":"trace[1437277711] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15412; }","duration":"368.05898ms","start":"2026-10-14T18:13:01.89235Z","end":"2026-10-14T18:13:02.260409Z","steps":["trace[1437277711] 'agreement among raft nodes before linearized reading' (duration: 367.847725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:02.260915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:13:01.892202Z","time spent":"368.694316ms","remote":"127.0.0.1:37024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"warn","ts":"2026-10-14T18:13:02.260941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.213675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:727434"} {"level":"warn","ts":"2026-10-14T18:13:02.260963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:13:01.892345Z","time spent":"368.598524ms","remote":"127.0.0.1:37024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"info","ts":"2026-10-14T18:13:02.261011Z","caller":"traceutil/trace.go:171","msg":"trace[1699264406] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15412; }","duration":"468.337717ms","start":"2026-10-14T18:13:01.792655Z","end":"2026-10-14T18:13:02.260993Z","steps":["trace[1699264406] 'agreement among raft nodes before linearized reading' (duration: 467.297081ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:02.261064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:13:01.792585Z","time spent":"468.462241ms","remote":"127.0.0.1:36960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":76,"response size":727458,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-10-14T18:17:05.668467Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14797} {"level":"info","ts":"2026-10-14T18:17:05.767695Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14797,"took":"95.871088ms","hash":3206452894,"current-db-size-bytes":54804480,"current-db-size":"55 MB","current-db-size-in-use-bytes":30654464,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T18:17:05.767768Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3206452894,"revision":14797,"compact-revision":12665} {"level":"info","ts":"2026-10-14T18:18:47.297362Z","caller":"traceutil/trace.go:171","msg":"trace[1234551374] linearizableReadLoop","detail":"{readStateIndex:18594; appliedIndex:18593; }","duration":"353.13923ms","start":"2026-10-14T18:18:46.94419Z","end":"2026-10-14T18:18:47.297329Z","steps":["trace[1234551374] 'read index received' (duration: 352.824702ms)","trace[1234551374] 'applied index is now lower than readState.Index' (duration: 313.108µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:18:47.297522Z","caller":"traceutil/trace.go:171","msg":"trace[1733806693] transaction","detail":"{read_only:false; response_revision:17977; number_of_response:1; }","duration":"432.549943ms","start":"2026-10-14T18:18:46.864961Z","end":"2026-10-14T18:18:47.297511Z","steps":["trace[1733806693] 'process raft request' (duration: 432.169013ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:18:47.297668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:18:46.864909Z","time spent":"432.643705ms","remote":"127.0.0.1:36960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20873,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:18:47.297688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.471359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:18:47.297767Z","caller":"traceutil/trace.go:171","msg":"trace[1594244744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17977; }","duration":"353.611283ms","start":"2026-10-14T18:18:46.944131Z","end":"2026-10-14T18:18:47.297742Z","steps":["trace[1594244744] 'agreement among raft nodes before linearized reading' (duration: 353.416898ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:18:47.297804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:18:46.944107Z","time spent":"353.692055ms","remote":"127.0.0.1:36786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T18:18:47.297784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.069495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-14T18:18:47.297877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.587663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-2xqpq\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-10-14T18:18:47.297928Z","caller":"traceutil/trace.go:171","msg":"trace[1366628391] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:17977; }","duration":"283.314851ms","start":"2026-10-14T18:18:47.014581Z","end":"2026-10-14T18:18:47.297896Z","steps":["trace[1366628391] 'agreement among raft nodes before linearized reading' (duration: 282.993462ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:18:47.297973Z","caller":"traceutil/trace.go:171","msg":"trace[1356506661] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-2xqpq; range_end:; response_count:1; response_revision:17977; }","duration":"190.741427ms","start":"2026-10-14T18:18:47.107211Z","end":"2026-10-14T18:18:47.297952Z","steps":["trace[1356506661] 'agreement among raft nodes before linearized reading' (duration: 190.46809ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:18:47.298086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.618929ms","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-10-14T18:18:47.298252Z","caller":"traceutil/trace.go:171","msg":"trace[1336560860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17977; }","duration":"279.887275ms","start":"2026-10-14T18:18:47.018332Z","end":"2026-10-14T18:18:47.298219Z","steps":["trace[1336560860] 'agreement among raft nodes before linearized reading' (duration: 279.389892ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:18:47.547088Z","caller":"traceutil/trace.go:171","msg":"trace[298281794] linearizableReadLoop","detail":"{readStateIndex:18595; appliedIndex:18594; }","duration":"241.388964ms","start":"2026-10-14T18:18:47.305658Z","end":"2026-10-14T18:18:47.547047Z","steps":["trace[298281794] 'read index received' (duration: 234.556261ms)","trace[298281794] 'applied index is now lower than readState.Index' (duration: 6.831163ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:18:47.547297Z","caller":"traceutil/trace.go:171","msg":"trace[566970524] transaction","detail":"{read_only:false; response_revision:17978; number_of_response:1; }","duration":"244.665507ms","start":"2026-10-14T18:18:47.302593Z","end":"2026-10-14T18:18:47.547259Z","steps":["trace[566970524] 'process raft request' (duration: 237.708561ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:18:47.548116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.245086ms","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-10-14T18:18:47.548172Z","caller":"traceutil/trace.go:171","msg":"trace[841006617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17978; }","duration":"173.372699ms","start":"2026-10-14T18:18:47.374788Z","end":"2026-10-14T18:18:47.54816Z","steps":["trace[841006617] 'agreement among raft nodes before linearized reading' (duration: 173.077522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:18:47.548341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.819214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:18:47.548382Z","caller":"traceutil/trace.go:171","msg":"trace[1953269369] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:17978; }","duration":"164.861245ms","start":"2026-10-14T18:18:47.383509Z","end":"2026-10-14T18:18:47.54837Z","steps":["trace[1953269369] 'agreement among raft nodes before linearized reading' (duration: 164.795843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:18:47.548647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.956024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:935883"} {"level":"info","ts":"2026-10-14T18:18:47.548705Z","caller":"traceutil/trace.go:171","msg":"trace[2042868807] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:17978; }","duration":"243.097277ms","start":"2026-10-14T18:18:47.305595Z","end":"2026-10-14T18:18:47.548692Z","steps":["trace[2042868807] 'agreement among raft nodes before linearized reading' (duration: 241.670271ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:21:06.325376Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8a5b61ed9120d6b3","local-member-applied-index":20003,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:21:06.336876Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-10-14T18:21:06.337021Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-10-14T18:22:05.676085Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17290} {"level":"info","ts":"2026-10-14T18:22:05.785876Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17290,"took":"105.477989ms","hash":3582340920,"current-db-size-bytes":54804480,"current-db-size":"55 MB","current-db-size-in-use-bytes":36225024,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-14T18:22:05.785998Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3582340920,"revision":17290,"compact-revision":14797} {"level":"warn","ts":"2026-10-14T18:26:30.928096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.568457ms","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-10-14T18:26:30.928488Z","caller":"traceutil/trace.go:171","msg":"trace[1257860436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21815; }","duration":"190.022099ms","start":"2026-10-14T18:26:30.738431Z","end":"2026-10-14T18:26:30.928453Z","steps":["trace[1257860436] 'range keys from in-memory index tree' (duration: 189.228479ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:26:30.93073Z","caller":"traceutil/trace.go:171","msg":"trace[384572358] transaction","detail":"{read_only:false; response_revision:21816; number_of_response:1; }","duration":"107.734394ms","start":"2026-10-14T18:26:30.822965Z","end":"2026-10-14T18:26:30.930699Z","steps":["trace[384572358] 'process raft request' (duration: 107.15258ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:26:36.708524Z","caller":"traceutil/trace.go:171","msg":"trace[912934428] linearizableReadLoop","detail":"{readStateIndex:22578; appliedIndex:22577; }","duration":"168.100937ms","start":"2026-10-14T18:26:36.540389Z","end":"2026-10-14T18:26:36.70849Z","steps":["trace[912934428] 'read index received' (duration: 167.921252ms)","trace[912934428] 'applied index is now lower than readState.Index' (duration: 178.185µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:26:36.708585Z","caller":"traceutil/trace.go:171","msg":"trace[394760716] transaction","detail":"{read_only:false; response_revision:21841; number_of_response:1; }","duration":"171.598034ms","start":"2026-10-14T18:26:36.536939Z","end":"2026-10-14T18:26:36.708537Z","steps":["trace[394760716] 'process raft request' (duration: 171.339017ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:36.708915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.336752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-10-14T18:26:36.708906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.466496ms","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-10-14T18:26:36.709005Z","caller":"traceutil/trace.go:171","msg":"trace[625637984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21841; }","duration":"162.501666ms","start":"2026-10-14T18:26:36.546485Z","end":"2026-10-14T18:26:36.708986Z","steps":["trace[625637984] 'agreement among raft nodes before linearized reading' (duration: 162.242299ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:26:36.709015Z","caller":"traceutil/trace.go:171","msg":"trace[1689178052] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21841; }","duration":"168.673951ms","start":"2026-10-14T18:26:36.540329Z","end":"2026-10-14T18:26:36.709003Z","steps":["trace[1689178052] 'agreement among raft nodes before linearized reading' (duration: 168.378804ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:36.97879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.516356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:26:36.978951Z","caller":"traceutil/trace.go:171","msg":"trace[251451434] transaction","detail":"{read_only:false; response_revision:21842; number_of_response:1; }","duration":"263.578713ms","start":"2026-10-14T18:26:36.715347Z","end":"2026-10-14T18:26:36.978925Z","steps":["trace[251451434] 'process raft request' (duration: 102.811391ms)","trace[251451434] 'compare' (duration: 160.074345ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:26:37.262118Z","caller":"traceutil/trace.go:171","msg":"trace[2068194148] transaction","detail":"{read_only:false; response_revision:21843; number_of_response:1; }","duration":"546.36433ms","start":"2026-10-14T18:26:36.715715Z","end":"2026-10-14T18:26:37.262079Z","steps":["trace[2068194148] 'process raft request' (duration: 459.402229ms)","trace[2068194148] 'compare' (duration: 86.657694ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:26:37.262344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:26:36.71568Z","time spent":"546.565634ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:26:37.26239Z","caller":"traceutil/trace.go:171","msg":"trace[5984955] linearizableReadLoop","detail":"{readStateIndex:22581; appliedIndex:22578; }","duration":"454.889923ms","start":"2026-10-14T18:26:36.807465Z","end":"2026-10-14T18:26:37.262355Z","steps":["trace[5984955] 'read index received' (duration: 10.698049ms)","trace[5984955] 'applied index is now lower than readState.Index' (duration: 444.190184ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:26:37.262439Z","caller":"traceutil/trace.go:171","msg":"trace[344860288] transaction","detail":"{read_only:false; response_revision:21844; number_of_response:1; }","duration":"545.5468ms","start":"2026-10-14T18:26:36.716849Z","end":"2026-10-14T18:26:37.262396Z","steps":["trace[344860288] 'process raft request' (duration: 545.15222ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:37.262676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:26:36.716821Z","time spent":"545.722073ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:26:37.262641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.1578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-10-14T18:26:37.262777Z","caller":"traceutil/trace.go:171","msg":"trace[838324346] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:21844; }","duration":"455.347906ms","start":"2026-10-14T18:26:36.807407Z","end":"2026-10-14T18:26:37.262755Z","steps":["trace[838324346] 'agreement among raft nodes before linearized reading' (duration: 455.068129ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:37.262826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:26:36.807381Z","time spent":"455.434337ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":557,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-10-14T18:26:37.271568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.404914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T18:26:37.271731Z","caller":"traceutil/trace.go:171","msg":"trace[239538875] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:21845; }","duration":"416.578838ms","start":"2026-10-14T18:26:36.855081Z","end":"2026-10-14T18:26:37.27166Z","steps":["trace[239538875] 'agreement among raft nodes before linearized reading' (duration: 416.217429ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:37.271828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:26:36.855061Z","time spent":"416.706672ms","remote":"127.0.0.1:37244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":32,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-10-14T18:26:37.272281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.238253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:26:37.272367Z","caller":"traceutil/trace.go:171","msg":"trace[1852199979] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:21845; }","duration":"353.382187ms","start":"2026-10-14T18:26:36.918963Z","end":"2026-10-14T18:26:37.272346Z","steps":["trace[1852199979] 'agreement among raft nodes before linearized reading' (duration: 353.247184ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:26:37.27238Z","caller":"traceutil/trace.go:171","msg":"trace[333556002] transaction","detail":"{read_only:false; response_revision:21845; number_of_response:1; }","duration":"311.239104ms","start":"2026-10-14T18:26:36.961123Z","end":"2026-10-14T18:26:37.272362Z","steps":["trace[333556002] 'process raft request' (duration: 310.068554ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:37.272422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:26:36.918931Z","time spent":"353.478159ms","remote":"127.0.0.1:51980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"warn","ts":"2026-10-14T18:26:37.272412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.89403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T18:26:37.27243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.608552ms","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-10-14T18:26:37.272484Z","caller":"traceutil/trace.go:171","msg":"trace[452119538] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:21845; }","duration":"285.015973ms","start":"2026-10-14T18:26:36.987448Z","end":"2026-10-14T18:26:37.272464Z","steps":["trace[452119538] 'agreement among raft nodes before linearized reading' (duration: 284.862789ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:37.272497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:26:36.961091Z","time spent":"311.329317ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:26:37.272538Z","caller":"traceutil/trace.go:171","msg":"trace[297422657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21845; }","duration":"303.793207ms","start":"2026-10-14T18:26:36.968721Z","end":"2026-10-14T18:26:37.272515Z","steps":["trace[297422657] 'agreement among raft nodes before linearized reading' (duration: 303.448899ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:37.272598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:26:36.968708Z","time spent":"303.874899ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-14T18:26:37.272597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.595796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:26:37.272721Z","caller":"traceutil/trace.go:171","msg":"trace[558655380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21845; }","duration":"327.786111ms","start":"2026-10-14T18:26:36.944906Z","end":"2026-10-14T18:26:37.272692Z","steps":["trace[558655380] 'agreement among raft nodes before linearized reading' (duration: 327.537865ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:37.272779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:26:36.944855Z","time spent":"327.914765ms","remote":"127.0.0.1:36786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T18:26:37.272901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.875282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:31 size:266792"} {"level":"info","ts":"2026-10-14T18:26:37.272999Z","caller":"traceutil/trace.go:171","msg":"trace[1662811489] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:31; response_revision:21845; }","duration":"267.987265ms","start":"2026-10-14T18:26:37.004987Z","end":"2026-10-14T18:26:37.272975Z","steps":["trace[1662811489] 'agreement among raft nodes before linearized reading' (duration: 267.274977ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:37.655188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.506312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:26:37.655339Z","caller":"traceutil/trace.go:171","msg":"trace[1709853734] transaction","detail":"{read_only:false; response_revision:21846; number_of_response:1; }","duration":"385.67903ms","start":"2026-10-14T18:26:37.269618Z","end":"2026-10-14T18:26:37.655297Z","steps":["trace[1709853734] 'process raft request' (duration: 167.968754ms)","trace[1709853734] 'compare' (duration: 217.267815ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:26:37.655504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:26:37.269585Z","time spent":"385.832794ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:26:37.65549Z","caller":"traceutil/trace.go:171","msg":"trace[919384224] linearizableReadLoop","detail":"{readStateIndex:22583; appliedIndex:22582; }","duration":"381.582367ms","start":"2026-10-14T18:26:37.273874Z","end":"2026-10-14T18:26:37.655456Z","steps":["trace[919384224] 'read index received' (duration: 163.734337ms)","trace[919384224] 'applied index is now lower than readState.Index' (duration: 217.84562ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:26:37.65615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.261694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:26:37.656224Z","caller":"traceutil/trace.go:171","msg":"trace[507623173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21846; }","duration":"382.381137ms","start":"2026-10-14T18:26:37.273822Z","end":"2026-10-14T18:26:37.656204Z","steps":["trace[507623173] 'agreement among raft nodes before linearized reading' (duration: 382.249024ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:37.656269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:26:37.273809Z","time spent":"382.449069ms","remote":"127.0.0.1:36782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-14T18:26:37.656328Z","caller":"traceutil/trace.go:171","msg":"trace[1394924096] transaction","detail":"{read_only:false; response_revision:21847; number_of_response:1; }","duration":"377.479363ms","start":"2026-10-14T18:26:37.278826Z","end":"2026-10-14T18:26:37.656305Z","steps":["trace[1394924096] 'process raft request' (duration: 376.477487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:37.656435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:26:37.278794Z","time spent":"377.593836ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:26:37.656413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.217433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T18:26:37.656505Z","caller":"traceutil/trace.go:171","msg":"trace[1188242036] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:21847; }","duration":"361.349437ms","start":"2026-10-14T18:26:37.295136Z","end":"2026-10-14T18:26:37.656486Z","steps":["trace[1188242036] 'agreement among raft nodes before linearized reading' (duration: 361.153972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:37.656598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:26:37.295115Z","time spent":"361.46917ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":3,"response size":32,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"info","ts":"2026-10-14T18:26:37.826464Z","caller":"traceutil/trace.go:171","msg":"trace[55489945] transaction","detail":"{read_only:false; response_revision:21848; number_of_response:1; }","duration":"118.208029ms","start":"2026-10-14T18:26:37.708216Z","end":"2026-10-14T18:26:37.826424Z","steps":["trace[55489945] 'process raft request' (duration: 115.599764ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:26:38.005811Z","caller":"traceutil/trace.go:171","msg":"trace[1486160230] linearizableReadLoop","detail":"{readStateIndex:22587; appliedIndex:22584; }","duration":"271.078002ms","start":"2026-10-14T18:26:37.734707Z","end":"2026-10-14T18:26:38.005785Z","steps":["trace[1486160230] 'read index received' (duration: 89.220468ms)","trace[1486160230] 'applied index is now lower than readState.Index' (duration: 181.856544ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:26:38.005845Z","caller":"traceutil/trace.go:171","msg":"trace[1396904662] transaction","detail":"{read_only:false; response_revision:21849; number_of_response:1; }","duration":"295.640261ms","start":"2026-10-14T18:26:37.710173Z","end":"2026-10-14T18:26:38.005813Z","steps":["trace[1396904662] 'process raft request' (duration: 263.194493ms)","trace[1396904662] 'compare' (duration: 32.150551ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:26:38.005995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.253356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:26:38.006055Z","caller":"traceutil/trace.go:171","msg":"trace[1742976062] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:21851; }","duration":"271.369879ms","start":"2026-10-14T18:26:37.734671Z","end":"2026-10-14T18:26:38.006041Z","steps":["trace[1742976062] 'agreement among raft nodes before linearized reading' (duration: 271.231646ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:26:38.006353Z","caller":"traceutil/trace.go:171","msg":"trace[1249311944] transaction","detail":"{read_only:false; response_revision:21850; number_of_response:1; }","duration":"294.243696ms","start":"2026-10-14T18:26:37.712095Z","end":"2026-10-14T18:26:38.006339Z","steps":["trace[1249311944] 'process raft request' (duration: 293.601109ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:26:38.006626Z","caller":"traceutil/trace.go:171","msg":"trace[2007562974] transaction","detail":"{read_only:false; response_revision:21851; number_of_response:1; }","duration":"225.376141ms","start":"2026-10-14T18:26:37.781232Z","end":"2026-10-14T18:26:38.006609Z","steps":["trace[2007562974] 'process raft request' (duration: 224.509749ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:27:05.683607Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19953} {"level":"info","ts":"2026-10-14T18:27:05.810541Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19953,"took":"122.358531ms","hash":3716694424,"current-db-size-bytes":54804480,"current-db-size":"55 MB","current-db-size-in-use-bytes":30949376,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T18:27:05.810627Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3716694424,"revision":19953,"compact-revision":17290} {"level":"info","ts":"2026-10-14T18:30:30.249239Z","caller":"traceutil/trace.go:171","msg":"trace[1928634882] transaction","detail":"{read_only:false; response_revision:24326; number_of_response:1; }","duration":"161.809982ms","start":"2026-10-14T18:30:30.087352Z","end":"2026-10-14T18:30:30.249162Z","steps":["trace[1928634882] 'process raft request' (duration: 161.471513ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:30:30.63054Z","caller":"traceutil/trace.go:171","msg":"trace[1188454284] transaction","detail":"{read_only:false; response_revision:24327; number_of_response:1; }","duration":"349.588884ms","start":"2026-10-14T18:30:30.280918Z","end":"2026-10-14T18:30:30.630507Z","steps":["trace[1188454284] 'process raft request' (duration: 349.353838ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:30:30.630659Z","caller":"traceutil/trace.go:171","msg":"trace[85384016] linearizableReadLoop","detail":"{readStateIndex:25139; appliedIndex:25139; }","duration":"349.45909ms","start":"2026-10-14T18:30:30.281153Z","end":"2026-10-14T18:30:30.630613Z","steps":["trace[85384016] 'read index received' (duration: 349.42384ms)","trace[85384016] 'applied index is now lower than readState.Index' (duration: 31.67µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:30:30.630766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:30:30.280895Z","time spent":"349.748308ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:30:30.630984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.782379ms","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":"info","ts":"2026-10-14T18:30:30.631025Z","caller":"traceutil/trace.go:171","msg":"trace[525460437] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:24327; }","duration":"349.886602ms","start":"2026-10-14T18:30:30.281126Z","end":"2026-10-14T18:30:30.631013Z","steps":["trace[525460437] 'agreement among raft nodes before linearized reading' (duration: 349.702757ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:30:30.631071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:30:30.281115Z","time spent":"349.945363ms","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-10-14T18:30:30.703454Z","caller":"traceutil/trace.go:171","msg":"trace[1952149623] transaction","detail":"{read_only:false; response_revision:24329; number_of_response:1; }","duration":"169.240057ms","start":"2026-10-14T18:30:30.534171Z","end":"2026-10-14T18:30:30.703411Z","steps":["trace[1952149623] 'process raft request' (duration: 169.127414ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:30:30.703648Z","caller":"traceutil/trace.go:171","msg":"trace[1936782183] transaction","detail":"{read_only:false; response_revision:24328; number_of_response:1; }","duration":"220.043192ms","start":"2026-10-14T18:30:30.483585Z","end":"2026-10-14T18:30:30.703628Z","steps":["trace[1936782183] 'process raft request' (duration: 219.461427ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:30:30.703654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.404435ms","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-10-14T18:30:30.703722Z","caller":"traceutil/trace.go:171","msg":"trace[1710038222] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24329; }","duration":"209.529889ms","start":"2026-10-14T18:30:30.494175Z","end":"2026-10-14T18:30:30.703705Z","steps":["trace[1710038222] 'agreement among raft nodes before linearized reading' (duration: 209.266412ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:30:30.703658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.435243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-14T18:30:30.703853Z","caller":"traceutil/trace.go:171","msg":"trace[328501922] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24329; }","duration":"383.70559ms","start":"2026-10-14T18:30:30.320117Z","end":"2026-10-14T18:30:30.703822Z","steps":["trace[328501922] 'agreement among raft nodes before linearized reading' (duration: 383.269679ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:30:30.703931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:30:30.32009Z","time spent":"383.826933ms","remote":"127.0.0.1:36960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-14T18:30:30.704144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.844812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:34 size:288460"} {"level":"info","ts":"2026-10-14T18:30:30.704211Z","caller":"traceutil/trace.go:171","msg":"trace[536744283] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:34; response_revision:24329; }","duration":"377.917114ms","start":"2026-10-14T18:30:30.32628Z","end":"2026-10-14T18:30:30.704197Z","steps":["trace[536744283] 'agreement among raft nodes before linearized reading' (duration: 377.220907ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:30:30.704247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:30:30.326246Z","time spent":"377.989706ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":34,"response size":288484,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"info","ts":"2026-10-14T18:31:52.580418Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000065b6.wal"} {"level":"warn","ts":"2026-10-14T18:32:02.283216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.478695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-10-14T18:32:02.283333Z","caller":"traceutil/trace.go:171","msg":"trace[160168877] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25278; }","duration":"126.671619ms","start":"2026-10-14T18:32:02.156627Z","end":"2026-10-14T18:32:02.283299Z","steps":["trace[160168877] 'range keys from in-memory index tree' (duration: 126.216238ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:05.704298Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21996} {"level":"info","ts":"2026-10-14T18:32:05.832888Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21996,"took":"122.770991ms","hash":1076363197,"current-db-size-bytes":56500224,"current-db-size":"56 MB","current-db-size-in-use-bytes":44544000,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-10-14T18:32:05.832969Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1076363197,"revision":21996,"compact-revision":19953} {"level":"info","ts":"2026-10-14T18:34:01.456214Z","caller":"traceutil/trace.go:171","msg":"trace[1895496363] linearizableReadLoop","detail":"{readStateIndex:27221; appliedIndex:27220; }","duration":"271.007991ms","start":"2026-10-14T18:34:01.185181Z","end":"2026-10-14T18:34:01.456189Z","steps":["trace[1895496363] 'read index received' (duration: 270.748446ms)","trace[1895496363] 'applied index is now lower than readState.Index' (duration: 258.265µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:34:01.45634Z","caller":"traceutil/trace.go:171","msg":"trace[498999674] transaction","detail":"{read_only:false; response_revision:26358; number_of_response:1; }","duration":"351.191394ms","start":"2026-10-14T18:34:01.10511Z","end":"2026-10-14T18:34:01.456302Z","steps":["trace[498999674] 'process raft request' (duration: 350.904078ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:34:01.456465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.266388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-14T18:34:01.456529Z","caller":"traceutil/trace.go:171","msg":"trace[678188803] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26358; }","duration":"271.395841ms","start":"2026-10-14T18:34:01.185113Z","end":"2026-10-14T18:34:01.456508Z","steps":["trace[678188803] 'agreement among raft nodes before linearized reading' (duration: 271.223247ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:34:01.456588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:34:01.105076Z","time spent":"351.351748ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:34:01.783632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.075306ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:34:01.783841Z","caller":"traceutil/trace.go:171","msg":"trace[1547295185] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26358; }","duration":"191.294201ms","start":"2026-10-14T18:34:01.592515Z","end":"2026-10-14T18:34:01.783809Z","steps":["trace[1547295185] 'range keys from in-memory index tree' (duration: 191.056046ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:34:01.784557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.935043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:34:01.784781Z","caller":"traceutil/trace.go:171","msg":"trace[316205354] linearizableReadLoop","detail":"{readStateIndex:27222; appliedIndex:27221; }","duration":"320.698458ms","start":"2026-10-14T18:34:01.464005Z","end":"2026-10-14T18:34:01.784704Z","steps":["trace[316205354] 'read index received' (duration: 121.322124ms)","trace[316205354] 'applied index is now lower than readState.Index' (duration: 199.374354ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:34:01.784784Z","caller":"traceutil/trace.go:171","msg":"trace[1026505140] transaction","detail":"{read_only:false; response_revision:26359; number_of_response:1; }","duration":"323.122889ms","start":"2026-10-14T18:34:01.461604Z","end":"2026-10-14T18:34:01.784727Z","steps":["trace[1026505140] 'process raft request' (duration: 123.870398ms)","trace[1026505140] 'compare' (duration: 197.99845ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:34:01.784938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:34:01.461578Z","time spent":"323.262833ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:34:01.785043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.040977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-14T18:34:01.785081Z","caller":"traceutil/trace.go:171","msg":"trace[686697056] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26359; }","duration":"321.112079ms","start":"2026-10-14T18:34:01.463957Z","end":"2026-10-14T18:34:01.785069Z","steps":["trace[686697056] 'agreement among raft nodes before linearized reading' (duration: 320.899593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:34:01.785119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:34:01.463945Z","time spent":"321.1652ms","remote":"127.0.0.1:36960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-14T18:34:01.796454Z","caller":"traceutil/trace.go:171","msg":"trace[667728016] transaction","detail":"{read_only:false; response_revision:26360; number_of_response:1; }","duration":"168.11677ms","start":"2026-10-14T18:34:01.628311Z","end":"2026-10-14T18:34:01.796428Z","steps":["trace[667728016] 'process raft request' (duration: 167.945385ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:34:01.796547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.828264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-14T18:34:01.796608Z","caller":"traceutil/trace.go:171","msg":"trace[150858885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26360; }","duration":"187.960857ms","start":"2026-10-14T18:34:01.60863Z","end":"2026-10-14T18:34:01.796591Z","steps":["trace[150858885] 'agreement among raft nodes before linearized reading' (duration: 187.780052ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:34:01.796958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.653333ms","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":"info","ts":"2026-10-14T18:34:01.79709Z","caller":"traceutil/trace.go:171","msg":"trace[1666064369] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:26360; }","duration":"231.88609ms","start":"2026-10-14T18:34:01.56517Z","end":"2026-10-14T18:34:01.797056Z","steps":["trace[1666064369] 'agreement among raft nodes before linearized reading' (duration: 231.592362ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:34:02.02792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.625168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:34:02.028178Z","caller":"traceutil/trace.go:171","msg":"trace[1106735644] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26361; }","duration":"138.944466ms","start":"2026-10-14T18:34:01.889202Z","end":"2026-10-14T18:34:02.028146Z","steps":["trace[1106735644] 'range keys from in-memory index tree' (duration: 138.441144ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:34:58.549851Z","caller":"traceutil/trace.go:171","msg":"trace[1150578520] transaction","detail":"{read_only:false; response_revision:26884; number_of_response:1; }","duration":"163.955515ms","start":"2026-10-14T18:34:58.385864Z","end":"2026-10-14T18:34:58.549819Z","steps":["trace[1150578520] 'process raft request' (duration: 163.73461ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:34:58.551253Z","caller":"traceutil/trace.go:171","msg":"trace[779681922] linearizableReadLoop","detail":"{readStateIndex:27762; appliedIndex:27759; }","duration":"110.471722ms","start":"2026-10-14T18:34:58.440742Z","end":"2026-10-14T18:34:58.551214Z","steps":["trace[779681922] 'read index received' (duration: 108.919233ms)","trace[779681922] 'applied index is now lower than readState.Index' (duration: 1.551059ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:34:58.551324Z","caller":"traceutil/trace.go:171","msg":"trace[1143715140] transaction","detail":"{read_only:false; response_revision:26886; number_of_response:1; }","duration":"138.812904ms","start":"2026-10-14T18:34:58.41248Z","end":"2026-10-14T18:34:58.551292Z","steps":["trace[1143715140] 'process raft request' (duration: 138.65217ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:34:58.551309Z","caller":"traceutil/trace.go:171","msg":"trace[375924908] transaction","detail":"{read_only:false; response_revision:26885; number_of_response:1; }","duration":"148.830335ms","start":"2026-10-14T18:34:58.402434Z","end":"2026-10-14T18:34:58.551264Z","steps":["trace[375924908] 'process raft request' (duration: 148.453906ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:34:58.551594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.829402ms","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-10-14T18:34:58.551689Z","caller":"traceutil/trace.go:171","msg":"trace[947673664] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26886; }","duration":"111.000155ms","start":"2026-10-14T18:34:58.440669Z","end":"2026-10-14T18:34:58.551669Z","steps":["trace[947673664] 'agreement among raft nodes before linearized reading' (duration: 110.735639ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:04.182672Z","caller":"traceutil/trace.go:171","msg":"trace[2086997351] transaction","detail":"{read_only:false; response_revision:26952; number_of_response:1; }","duration":"120.72685ms","start":"2026-10-14T18:35:04.061914Z","end":"2026-10-14T18:35:04.182641Z","steps":["trace[2086997351] 'process raft request' (duration: 120.520755ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:04.435905Z","caller":"traceutil/trace.go:171","msg":"trace[1629830181] linearizableReadLoop","detail":"{readStateIndex:27830; appliedIndex:27829; }","duration":"208.439011ms","start":"2026-10-14T18:35:04.227443Z","end":"2026-10-14T18:35:04.435882Z","steps":["trace[1629830181] 'read index received' (duration: 208.212425ms)","trace[1629830181] 'applied index is now lower than readState.Index' (duration: 225.616µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:35:04.43599Z","caller":"traceutil/trace.go:171","msg":"trace[1598376861] transaction","detail":"{read_only:false; response_revision:26953; number_of_response:1; }","duration":"236.430444ms","start":"2026-10-14T18:35:04.199517Z","end":"2026-10-14T18:35:04.435948Z","steps":["trace[1598376861] 'process raft request' (duration: 236.135606ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:35:04.436292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.81516ms","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-10-14T18:35:04.436443Z","caller":"traceutil/trace.go:171","msg":"trace[1540295712] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26953; }","duration":"209.029286ms","start":"2026-10-14T18:35:04.227382Z","end":"2026-10-14T18:35:04.436412Z","steps":["trace[1540295712] 'agreement among raft nodes before linearized reading' (duration: 208.621635ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:35:04.762876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.104516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:35:04.763197Z","caller":"traceutil/trace.go:171","msg":"trace[495543542] transaction","detail":"{read_only:false; response_revision:26954; number_of_response:1; }","duration":"488.747466ms","start":"2026-10-14T18:35:04.2744Z","end":"2026-10-14T18:35:04.763148Z","steps":["trace[495543542] 'process raft request' (duration: 352.261301ms)","trace[495543542] 'compare' (duration: 135.781478ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:35:04.763309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:35:04.274357Z","time spent":"488.9038ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:35:04.841264Z","caller":"traceutil/trace.go:171","msg":"trace[159623665] transaction","detail":"{read_only:false; response_revision:26955; number_of_response:1; }","duration":"409.808974ms","start":"2026-10-14T18:35:04.431414Z","end":"2026-10-14T18:35:04.841223Z","steps":["trace[159623665] 'process raft request' (duration: 409.279382ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:04.841343Z","caller":"traceutil/trace.go:171","msg":"trace[1616792065] linearizableReadLoop","detail":"{readStateIndex:27833; appliedIndex:27830; }","duration":"405.348944ms","start":"2026-10-14T18:35:04.435967Z","end":"2026-10-14T18:35:04.841316Z","steps":["trace[1616792065] 'read index received' (duration: 190.712037ms)","trace[1616792065] 'applied index is now lower than readState.Index' (duration: 214.633356ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:35:04.841492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:35:04.431383Z","time spent":"409.984479ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:35:04.841483Z","caller":"traceutil/trace.go:171","msg":"trace[1335452381] transaction","detail":"{read_only:false; response_revision:26956; number_of_response:1; }","duration":"399.533017ms","start":"2026-10-14T18:35:04.441923Z","end":"2026-10-14T18:35:04.841456Z","steps":["trace[1335452381] 'process raft request' (duration: 399.278781ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:35:04.841538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.042333ms","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-10-14T18:35:04.841536Z","caller":"traceutil/trace.go:171","msg":"trace[416079867] transaction","detail":"{read_only:false; response_revision:26958; number_of_response:1; }","duration":"256.275131ms","start":"2026-10-14T18:35:04.585224Z","end":"2026-10-14T18:35:04.841499Z","steps":["trace[416079867] 'process raft request' (duration: 256.166358ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:04.841618Z","caller":"traceutil/trace.go:171","msg":"trace[1654290061] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:26958; }","duration":"524.184877ms","start":"2026-10-14T18:35:04.317412Z","end":"2026-10-14T18:35:04.841597Z","steps":["trace[1654290061] 'agreement among raft nodes before linearized reading' (duration: 524.002162ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:35:04.84162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:35:04.441895Z","time spent":"399.662101ms","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:35:04.841692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:35:04.31738Z","time spent":"524.294239ms","remote":"127.0.0.1:58126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true "} {"level":"info","ts":"2026-10-14T18:35:04.84169Z","caller":"traceutil/trace.go:171","msg":"trace[581304737] transaction","detail":"{read_only:false; response_revision:26957; number_of_response:1; }","duration":"258.350534ms","start":"2026-10-14T18:35:04.583305Z","end":"2026-10-14T18:35:04.841655Z","steps":["trace[581304737] 'process raft request' (duration: 258.037006ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:35:04.842838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.572056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-10-14T18:35:04.842922Z","caller":"traceutil/trace.go:171","msg":"trace[4573911] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26961; }","duration":"273.705819ms","start":"2026-10-14T18:35:04.569197Z","end":"2026-10-14T18:35:04.842902Z","steps":["trace[4573911] 'agreement among raft nodes before linearized reading' (duration: 273.447312ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:35:04.843143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.471714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-14T18:35:04.843159Z","caller":"traceutil/trace.go:171","msg":"trace[1390459653] transaction","detail":"{read_only:false; response_revision:26959; number_of_response:1; }","duration":"256.438157ms","start":"2026-10-14T18:35:04.586694Z","end":"2026-10-14T18:35:04.843132Z","steps":["trace[1390459653] 'process raft request' (duration: 254.746744ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:35:04.843173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.143093ms","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-10-14T18:35:04.843199Z","caller":"traceutil/trace.go:171","msg":"trace[1151242446] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26961; }","duration":"232.582217ms","start":"2026-10-14T18:35:04.610597Z","end":"2026-10-14T18:35:04.843179Z","steps":["trace[1151242446] 'agreement among raft nodes before linearized reading' (duration: 232.366351ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:04.843236Z","caller":"traceutil/trace.go:171","msg":"trace[888030035] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26961; }","duration":"255.210365ms","start":"2026-10-14T18:35:04.588008Z","end":"2026-10-14T18:35:04.843218Z","steps":["trace[888030035] 'agreement among raft nodes before linearized reading' (duration: 255.03556ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:35:04.84327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.167691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-14T18:35:04.84334Z","caller":"traceutil/trace.go:171","msg":"trace[25589824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26961; }","duration":"217.290504ms","start":"2026-10-14T18:35:04.626023Z","end":"2026-10-14T18:35:04.843313Z","steps":["trace[25589824] 'agreement among raft nodes before linearized reading' (duration: 217.071619ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:05.01521Z","caller":"traceutil/trace.go:171","msg":"trace[959321533] transaction","detail":"{read_only:false; response_revision:26962; number_of_response:1; }","duration":"166.834776ms","start":"2026-10-14T18:35:04.848351Z","end":"2026-10-14T18:35:05.015186Z","steps":["trace[959321533] 'process raft request' (duration: 166.670762ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:05.016159Z","caller":"traceutil/trace.go:171","msg":"trace[1623683002] transaction","detail":"{read_only:false; response_revision:26965; number_of_response:1; }","duration":"165.959876ms","start":"2026-10-14T18:35:04.850182Z","end":"2026-10-14T18:35:05.016142Z","steps":["trace[1623683002] 'process raft request' (duration: 165.877844ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:05.016293Z","caller":"traceutil/trace.go:171","msg":"trace[1209763126] linearizableReadLoop","detail":"{readStateIndex:27843; appliedIndex:27839; }","duration":"164.647512ms","start":"2026-10-14T18:35:04.85161Z","end":"2026-10-14T18:35:05.016258Z","steps":["trace[1209763126] 'read index received' (duration: 163.501173ms)","trace[1209763126] 'applied index is now lower than readState.Index' (duration: 1.144719ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:35:05.016403Z","caller":"traceutil/trace.go:171","msg":"trace[1839664333] transaction","detail":"{read_only:false; response_revision:26964; number_of_response:1; }","duration":"167.812422ms","start":"2026-10-14T18:35:04.848573Z","end":"2026-10-14T18:35:05.016385Z","steps":["trace[1839664333] 'process raft request' (duration: 167.435502ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:35:05.016578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.321417ms","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":"warn","ts":"2026-10-14T18:35:05.016643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.008602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-10-14T18:35:05.016693Z","caller":"traceutil/trace.go:171","msg":"trace[792035924] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26966; }","duration":"165.118715ms","start":"2026-10-14T18:35:04.851562Z","end":"2026-10-14T18:35:05.01668Z","steps":["trace[792035924] 'agreement among raft nodes before linearized reading' (duration: 164.891199ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:05.016748Z","caller":"traceutil/trace.go:171","msg":"trace[208483026] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:26966; }","duration":"135.518781ms","start":"2026-10-14T18:35:04.881157Z","end":"2026-10-14T18:35:05.016676Z","steps":["trace[208483026] 'agreement among raft nodes before linearized reading' (duration: 135.273435ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:05.016408Z","caller":"traceutil/trace.go:171","msg":"trace[643281886] transaction","detail":"{read_only:false; response_revision:26963; number_of_response:1; }","duration":"167.789551ms","start":"2026-10-14T18:35:04.848519Z","end":"2026-10-14T18:35:05.016309Z","steps":["trace[643281886] 'process raft request' (duration: 167.399941ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:37:05.711562Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25309} {"level":"info","ts":"2026-10-14T18:37:05.877877Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25309,"took":"161.626905ms","hash":4002559241,"current-db-size-bytes":58187776,"current-db-size":"58 MB","current-db-size-in-use-bytes":38227968,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-14T18:37:05.877956Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4002559241,"revision":25309,"compact-revision":21996} {"level":"info","ts":"2026-10-14T18:38:39.890912Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8a5b61ed9120d6b3","local-member-applied-index":30004,"local-member-snapshot-index":20003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:38:39.898005Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-10-14T18:38:39.898189Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-10-14T18:38:48.075321Z","caller":"traceutil/trace.go:171","msg":"trace[1575259025] transaction","detail":"{read_only:false; response_revision:29138; number_of_response:1; }","duration":"124.629644ms","start":"2026-10-14T18:38:47.950641Z","end":"2026-10-14T18:38:48.07527Z","steps":["trace[1575259025] 'process raft request' (duration: 91.699956ms)","trace[1575259025] 'compare' (duration: 32.723343ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:42:05.718897Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28158} {"level":"info","ts":"2026-10-14T18:42:05.879318Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28158,"took":"154.327062ms","hash":3223256216,"current-db-size-bytes":58187776,"current-db-size":"58 MB","current-db-size-in-use-bytes":35180544,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-14T18:42:05.879417Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3223256216,"revision":28158,"compact-revision":25309} {"level":"info","ts":"2026-10-14T18:47:05.725344Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30789} {"level":"info","ts":"2026-10-14T18:47:05.885724Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30789,"took":"154.158992ms","hash":3784290013,"current-db-size-bytes":58187776,"current-db-size":"58 MB","current-db-size-in-use-bytes":31350784,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T18:47:05.885813Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3784290013,"revision":30789,"compact-revision":28158} {"level":"info","ts":"2026-10-14T18:52:05.736207Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33079} {"level":"info","ts":"2026-10-14T18:52:05.94517Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33079,"took":"202.603412ms","hash":3695854057,"current-db-size-bytes":58187776,"current-db-size":"58 MB","current-db-size-in-use-bytes":31035392,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T18:52:05.945263Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3695854057,"revision":33079,"compact-revision":30789} {"level":"info","ts":"2026-10-14T18:57:05.743762Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35348} {"level":"info","ts":"2026-10-14T18:57:05.901094Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35348,"took":"151.093675ms","hash":2999791334,"current-db-size-bytes":58187776,"current-db-size":"58 MB","current-db-size-in-use-bytes":31731712,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T18:57:05.901272Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2999791334,"revision":35348,"compact-revision":33079} {"level":"info","ts":"2026-10-14T18:59:32.550902Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8a5b61ed9120d6b3","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:59:32.558471Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-10-14T18:59:32.558612Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-10-14T19:02:05.751519Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37678} {"level":"info","ts":"2026-10-14T19:02:05.907425Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37678,"took":"151.681852ms","hash":2346391770,"current-db-size-bytes":59887616,"current-db-size":"60 MB","current-db-size-in-use-bytes":33386496,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-14T19:02:05.907513Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2346391770,"revision":37678,"compact-revision":35348} {"level":"info","ts":"2026-10-14T19:07:05.759295Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":40065} {"level":"info","ts":"2026-10-14T19:07:05.907321Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":40065,"took":"143.989439ms","hash":3548836074,"current-db-size-bytes":59887616,"current-db-size":"60 MB","current-db-size-in-use-bytes":32796672,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-14T19:07:05.907395Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3548836074,"revision":40065,"compact-revision":37678}