{"level":"warn","ts":"2026-08-19T09:12:17.821609Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-19T09:12:17.821741Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-08-19T09:12:17.821834Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-19T09:12:17.821867Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-08-19T09:12:17.821887Z","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-08-19T09:12:17.822662Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-08-19T09:12:17.822751Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-08-19T09:12:17.825497Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.219991ms"} {"level":"info","ts":"2026-08-19T09:12:17.844691Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-08-19T09:12:17.844879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-08-19T09:12:17.84492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-08-19T09:12:17.844943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-19T09:12:17.844963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-08-19T09:12:17.845036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-08-19T09:12:17.851893Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-19T09:12:17.854164Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-19T09:12:17.855455Z","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-08-19T09:12:17.858767Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-19T09:12:17.858929Z","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-08-19T09:12:17.859033Z","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-08-19T09:12:17.859046Z","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-08-19T09:12:17.859258Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-19T09:12:17.861008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-08-19T09:12:17.861432Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-08-19T09:12:17.862709Z","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-08-19T09:12:17.862824Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-08-19T09:12:17.86291Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-08-19T09:12:17.863178Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-19T09:12:17.86324Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-19T09:12:18.74577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-08-19T09:12:18.745833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-19T09:12:18.74587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-08-19T09:12:18.745888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-08-19T09:12:18.745897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-08-19T09:12:18.745909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-08-19T09:12:18.745922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-08-19T09:12:18.746806Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:12:18.747618Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-08-19T09:12:18.747623Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T09:12:18.747699Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T09:12:18.747916Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-19T09:12:18.74797Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-19T09:12:18.748081Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:12:18.74817Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:12:18.748204Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:12:18.751342Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-08-19T09:12:18.751459Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-19T09:13:01.148306Z","caller":"traceutil/trace.go:171","msg":"trace[1202685972] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"236.545906ms","start":"2026-08-19T09:13:00.911725Z","end":"2026-08-19T09:13:01.148271Z","steps":["trace[1202685972] 'process raft request' (duration: 236.011956ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:13:01.15463Z","caller":"traceutil/trace.go:171","msg":"trace[355460577] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:652; }","duration":"169.455053ms","start":"2026-08-19T09:13:00.985126Z","end":"2026-08-19T09:13:01.154581Z","steps":["trace[355460577] 'read index received' (duration: 162.359806ms)","trace[355460577] 'applied index is now lower than readState.Index' (duration: 7.093907ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:13:01.154687Z","caller":"traceutil/trace.go:171","msg":"trace[1894515794] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"196.168504ms","start":"2026-08-19T09:13:00.958477Z","end":"2026-08-19T09:13:01.154646Z","steps":["trace[1894515794] 'process raft request' (duration: 195.98254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:13:01.15484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.673427ms","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-08-19T09:13:01.154987Z","caller":"traceutil/trace.go:171","msg":"trace[786723614] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"169.883801ms","start":"2026-08-19T09:13:00.985078Z","end":"2026-08-19T09:13:01.154962Z","steps":["trace[786723614] 'agreement among raft nodes before linearized reading' (duration: 169.617186ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:13:01.578337Z","caller":"traceutil/trace.go:171","msg":"trace[1891916417] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"126.677504ms","start":"2026-08-19T09:13:01.451605Z","end":"2026-08-19T09:13:01.578282Z","steps":["trace[1891916417] 'read index received' (duration: 126.518651ms)","trace[1891916417] 'applied index is now lower than readState.Index' (duration: 157.532µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:13:01.578454Z","caller":"traceutil/trace.go:171","msg":"trace[654612890] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"369.625674ms","start":"2026-08-19T09:13:01.208777Z","end":"2026-08-19T09:13:01.578402Z","steps":["trace[654612890] 'process raft request' (duration: 369.336758ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:13:01.5787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.066282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xrh2s\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-19T09:13:01.578816Z","caller":"traceutil/trace.go:171","msg":"trace[2139141826] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xrh2s; range_end:; response_count:1; response_revision:634; }","duration":"127.235154ms","start":"2026-08-19T09:13:01.451553Z","end":"2026-08-19T09:13:01.578788Z","steps":["trace[2139141826] 'agreement among raft nodes before linearized reading' (duration: 126.868717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:13:01.579257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:13:01.208759Z","time spent":"369.810817ms","remote":"127.0.0.1:55342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T09:13:17.183152Z","caller":"traceutil/trace.go:171","msg":"trace[818198392] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"231.158244ms","start":"2026-08-19T09:13:16.951967Z","end":"2026-08-19T09:13:17.183125Z","steps":["trace[818198392] 'read index received' (duration: 153.441947ms)","trace[818198392] 'applied index is now lower than readState.Index' (duration: 77.714747ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:13:17.183426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.442149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-19T09:13:17.183513Z","caller":"traceutil/trace.go:171","msg":"trace[126905145] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:798; }","duration":"231.583882ms","start":"2026-08-19T09:13:16.95191Z","end":"2026-08-19T09:13:17.183493Z","steps":["trace[126905145] 'agreement among raft nodes before linearized reading' (duration: 231.364048ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:13:50.063832Z","caller":"traceutil/trace.go:171","msg":"trace[292486727] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"238.375113ms","start":"2026-08-19T09:13:49.825419Z","end":"2026-08-19T09:13:50.063794Z","steps":["trace[292486727] 'process raft request' (duration: 238.085268ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:14:34.419951Z","caller":"traceutil/trace.go:171","msg":"trace[166506803] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"188.454832ms","start":"2026-08-19T09:14:34.231449Z","end":"2026-08-19T09:14:34.419904Z","steps":["trace[166506803] 'process raft request' (duration: 188.224477ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:15:24.758832Z","caller":"traceutil/trace.go:171","msg":"trace[1184671162] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"112.153186ms","start":"2026-08-19T09:15:24.646629Z","end":"2026-08-19T09:15:24.758783Z","steps":["trace[1184671162] 'process raft request' (duration: 111.959642ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:16:48.555496Z","caller":"traceutil/trace.go:171","msg":"trace[669444128] transaction","detail":"{read_only:false; response_revision:2835; number_of_response:1; }","duration":"189.967107ms","start":"2026-08-19T09:16:48.365496Z","end":"2026-08-19T09:16:48.555463Z","steps":["trace[669444128] 'process raft request' (duration: 189.776093ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:16:48.676505Z","caller":"traceutil/trace.go:171","msg":"trace[729747776] transaction","detail":"{read_only:false; response_revision:2837; number_of_response:1; }","duration":"223.298458ms","start":"2026-08-19T09:16:48.453176Z","end":"2026-08-19T09:16:48.676475Z","steps":["trace[729747776] 'process raft request' (duration: 223.238567ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:16:48.676548Z","caller":"traceutil/trace.go:171","msg":"trace[532263005] transaction","detail":"{read_only:false; response_revision:2836; number_of_response:1; }","duration":"248.28243ms","start":"2026-08-19T09:16:48.428227Z","end":"2026-08-19T09:16:48.676509Z","steps":["trace[532263005] 'process raft request' (duration: 238.282117ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:20:47.613301Z","caller":"traceutil/trace.go:171","msg":"trace[1941211761] transaction","detail":"{read_only:false; response_revision:4840; number_of_response:1; }","duration":"110.962536ms","start":"2026-08-19T09:20:47.502317Z","end":"2026-08-19T09:20:47.613279Z","steps":["trace[1941211761] 'process raft request' (duration: 110.844834ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:20:49.241765Z","caller":"traceutil/trace.go:171","msg":"trace[1525694395] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"103.762747ms","start":"2026-08-19T09:20:49.137967Z","end":"2026-08-19T09:20:49.241729Z","steps":["trace[1525694395] 'process raft request' (duration: 103.552823ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:20:52.001172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.349935ms","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-08-19T09:20:52.00132Z","caller":"traceutil/trace.go:171","msg":"trace[1984631190] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5035; }","duration":"109.550258ms","start":"2026-08-19T09:20:51.891739Z","end":"2026-08-19T09:20:52.001289Z","steps":["trace[1984631190] 'range keys from in-memory index tree' (duration: 109.075399ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:20:52.196166Z","caller":"traceutil/trace.go:171","msg":"trace[1364842635] linearizableReadLoop","detail":"{readStateIndex:5228; appliedIndex:5227; }","duration":"185.01831ms","start":"2026-08-19T09:20:52.011121Z","end":"2026-08-19T09:20:52.19614Z","steps":["trace[1364842635] 'read index received' (duration: 184.979569ms)","trace[1364842635] 'applied index is now lower than readState.Index' (duration: 37.541µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:20:52.196345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.176933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:20:52.19629Z","caller":"traceutil/trace.go:171","msg":"trace[1377623151] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"189.705591ms","start":"2026-08-19T09:20:52.006545Z","end":"2026-08-19T09:20:52.19625Z","steps":["trace[1377623151] 'process raft request' (duration: 189.460726ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:20:52.196395Z","caller":"traceutil/trace.go:171","msg":"trace[1345932229] range","detail":"{range_begin:/registry/configmaps/monitoring/vector; range_end:; response_count:0; response_revision:5037; }","duration":"185.276186ms","start":"2026-08-19T09:20:52.011105Z","end":"2026-08-19T09:20:52.196381Z","steps":["trace[1345932229] 'agreement among raft nodes before linearized reading' (duration: 185.161083ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:20:52.233212Z","caller":"traceutil/trace.go:171","msg":"trace[1535973581] transaction","detail":"{read_only:false; response_revision:5038; number_of_response:1; }","duration":"218.828971ms","start":"2026-08-19T09:20:52.014361Z","end":"2026-08-19T09:20:52.23319Z","steps":["trace[1535973581] 'process raft request' (duration: 218.380432ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:20:52.233184Z","caller":"traceutil/trace.go:171","msg":"trace[1341842986] transaction","detail":"{read_only:false; response_revision:5039; number_of_response:1; }","duration":"212.182533ms","start":"2026-08-19T09:20:52.020963Z","end":"2026-08-19T09:20:52.233146Z","steps":["trace[1341842986] 'process raft request' (duration: 212.01043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:20:52.233588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.180218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-815e2ef3-d270-4fc8-9c44-8e4047d7408b\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-08-19T09:20:52.233637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.121134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-815e2ef3-d270-4fc8-9c44-8e4047d7408b\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-19T09:20:52.2337Z","caller":"traceutil/trace.go:171","msg":"trace[313830330] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-815e2ef3-d270-4fc8-9c44-8e4047d7408b; range_end:; response_count:1; response_revision:5039; }","duration":"195.235856ms","start":"2026-08-19T09:20:52.038446Z","end":"2026-08-19T09:20:52.233682Z","steps":["trace[313830330] 'agreement among raft nodes before linearized reading' (duration: 194.882879ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:20:52.233701Z","caller":"traceutil/trace.go:171","msg":"trace[1216996125] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-815e2ef3-d270-4fc8-9c44-8e4047d7408b; range_end:; response_count:1; response_revision:5039; }","duration":"126.524195ms","start":"2026-08-19T09:20:52.107145Z","end":"2026-08-19T09:20:52.233669Z","steps":["trace[1216996125] 'agreement among raft nodes before linearized reading' (duration: 126.000535ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:20:54.376432Z","caller":"traceutil/trace.go:171","msg":"trace[286072698] transaction","detail":"{read_only:false; response_revision:5084; number_of_response:1; }","duration":"171.50265ms","start":"2026-08-19T09:20:54.204885Z","end":"2026-08-19T09:20:54.376388Z","steps":["trace[286072698] 'process raft request' (duration: 109.180321ms)","trace[286072698] 'compare' (duration: 62.111955ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:20:54.385925Z","caller":"traceutil/trace.go:171","msg":"trace[1394670874] transaction","detail":"{read_only:false; response_revision:5085; number_of_response:1; }","duration":"143.773468ms","start":"2026-08-19T09:20:54.242109Z","end":"2026-08-19T09:20:54.385883Z","steps":["trace[1394670874] 'process raft request' (duration: 143.459701ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:20:54.386085Z","caller":"traceutil/trace.go:171","msg":"trace[1386657208] transaction","detail":"{read_only:false; response_revision:5086; number_of_response:1; }","duration":"132.124263ms","start":"2026-08-19T09:20:54.253936Z","end":"2026-08-19T09:20:54.386061Z","steps":["trace[1386657208] 'process raft request' (duration: 131.829137ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:20:54.38611Z","caller":"traceutil/trace.go:171","msg":"trace[1975652917] linearizableReadLoop","detail":"{readStateIndex:5278; appliedIndex:5276; }","duration":"142.575514ms","start":"2026-08-19T09:20:54.243482Z","end":"2026-08-19T09:20:54.386057Z","steps":["trace[1975652917] 'read index received' (duration: 70.578568ms)","trace[1975652917] 'applied index is now lower than readState.Index' (duration: 71.992706ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:20:54.386347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.827489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-815e2ef3-d270-4fc8-9c44-8e4047d7408b\" ","response":"range_response_count:1 size:3901"} {"level":"info","ts":"2026-08-19T09:20:54.386452Z","caller":"traceutil/trace.go:171","msg":"trace[1051628981] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-815e2ef3-d270-4fc8-9c44-8e4047d7408b; range_end:; response_count:1; response_revision:5086; }","duration":"142.961171ms","start":"2026-08-19T09:20:54.243463Z","end":"2026-08-19T09:20:54.386424Z","steps":["trace[1051628981] 'agreement among raft nodes before linearized reading' (duration: 142.687906ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:20:59.893301Z","caller":"traceutil/trace.go:171","msg":"trace[1696312451] linearizableReadLoop","detail":"{readStateIndex:5417; appliedIndex:5416; }","duration":"109.070969ms","start":"2026-08-19T09:20:59.784197Z","end":"2026-08-19T09:20:59.893268Z","steps":["trace[1696312451] 'read index received' (duration: 74.492853ms)","trace[1696312451] 'applied index is now lower than readState.Index' (duration: 34.576736ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:20:59.893352Z","caller":"traceutil/trace.go:171","msg":"trace[321027977] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"109.401126ms","start":"2026-08-19T09:20:59.783896Z","end":"2026-08-19T09:20:59.893297Z","steps":["trace[321027977] 'process raft request' (duration: 74.762429ms)","trace[321027977] 'compare' (duration: 34.462983ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:20:59.893723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.492147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-08-19T09:20:59.893835Z","caller":"traceutil/trace.go:171","msg":"trace[58912587] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5219; }","duration":"109.625729ms","start":"2026-08-19T09:20:59.78418Z","end":"2026-08-19T09:20:59.893805Z","steps":["trace[58912587] 'agreement among raft nodes before linearized reading' (duration: 109.204472ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:21:05.643611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.949045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:21:05.643916Z","caller":"traceutil/trace.go:171","msg":"trace[956565891] linearizableReadLoop","detail":"{readStateIndex:5510; appliedIndex:5508; }","duration":"241.066919ms","start":"2026-08-19T09:21:05.402829Z","end":"2026-08-19T09:21:05.643896Z","steps":["trace[956565891] 'read index received' (duration: 77.491362ms)","trace[956565891] 'applied index is now lower than readState.Index' (duration: 163.574217ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:21:05.644102Z","caller":"traceutil/trace.go:171","msg":"trace[139045291] transaction","detail":"{read_only:false; response_revision:5309; number_of_response:1; }","duration":"243.296791ms","start":"2026-08-19T09:21:05.400758Z","end":"2026-08-19T09:21:05.644054Z","steps":["trace[139045291] 'process raft request' (duration: 79.475549ms)","trace[139045291] 'compare' (duration: 162.590439ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:21:05.64428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.444516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130338"} {"level":"info","ts":"2026-08-19T09:21:05.644324Z","caller":"traceutil/trace.go:171","msg":"trace[311104237] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5310; }","duration":"241.519858ms","start":"2026-08-19T09:21:05.402793Z","end":"2026-08-19T09:21:05.644313Z","steps":["trace[311104237] 'agreement among raft nodes before linearized reading' (duration: 241.205762ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:21:05.644556Z","caller":"traceutil/trace.go:171","msg":"trace[951114275] transaction","detail":"{read_only:false; response_revision:5310; number_of_response:1; }","duration":"242.217322ms","start":"2026-08-19T09:21:05.40231Z","end":"2026-08-19T09:21:05.644528Z","steps":["trace[951114275] 'process raft request' (duration: 241.481247ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:21:08.520764Z","caller":"traceutil/trace.go:171","msg":"trace[1165136113] transaction","detail":"{read_only:false; response_revision:5363; number_of_response:1; }","duration":"142.085403ms","start":"2026-08-19T09:21:08.378645Z","end":"2026-08-19T09:21:08.52073Z","steps":["trace[1165136113] 'process raft request' (duration: 120.063229ms)","trace[1165136113] 'compare' (duration: 21.897092ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:21:08.532491Z","caller":"traceutil/trace.go:171","msg":"trace[515492005] transaction","detail":"{read_only:false; response_revision:5364; number_of_response:1; }","duration":"153.208987ms","start":"2026-08-19T09:21:08.379245Z","end":"2026-08-19T09:21:08.532454Z","steps":["trace[515492005] 'process raft request' (duration: 152.940842ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:21:08.532597Z","caller":"traceutil/trace.go:171","msg":"trace[1930879554] transaction","detail":"{read_only:false; response_revision:5365; number_of_response:1; }","duration":"153.271248ms","start":"2026-08-19T09:21:08.379292Z","end":"2026-08-19T09:21:08.532563Z","steps":["trace[1930879554] 'process raft request' (duration: 153.073315ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:21:08.532612Z","caller":"traceutil/trace.go:171","msg":"trace[523289072] transaction","detail":"{read_only:false; response_revision:5366; number_of_response:1; }","duration":"153.261029ms","start":"2026-08-19T09:21:08.379312Z","end":"2026-08-19T09:21:08.532573Z","steps":["trace[523289072] 'process raft request' (duration: 153.123156ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:21:08.532907Z","caller":"traceutil/trace.go:171","msg":"trace[1030592448] transaction","detail":"{read_only:false; response_revision:5367; number_of_response:1; }","duration":"152.590197ms","start":"2026-08-19T09:21:08.380292Z","end":"2026-08-19T09:21:08.532882Z","steps":["trace[1030592448] 'process raft request' (duration: 152.198368ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:21:08.53323Z","caller":"traceutil/trace.go:171","msg":"trace[1755454664] linearizableReadLoop","detail":"{readStateIndex:5568; appliedIndex:5563; }","duration":"151.995556ms","start":"2026-08-19T09:21:08.381223Z","end":"2026-08-19T09:21:08.533219Z","steps":["trace[1755454664] 'read index received' (duration: 117.511732ms)","trace[1755454664] 'applied index is now lower than readState.Index' (duration: 34.482724ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:21:08.533392Z","caller":"traceutil/trace.go:171","msg":"trace[617905981] transaction","detail":"{read_only:false; response_revision:5368; number_of_response:1; }","duration":"152.026567ms","start":"2026-08-19T09:21:08.381339Z","end":"2026-08-19T09:21:08.533366Z","steps":["trace[617905981] 'process raft request' (duration: 151.470316ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:21:08.533425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.183439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-815e2ef3-d270-4fc8-9c44-8e4047d7408b\" ","response":"range_response_count:1 size:1275"} {"level":"info","ts":"2026-08-19T09:21:08.533564Z","caller":"traceutil/trace.go:171","msg":"trace[1949973040] range","detail":"{range_begin:/registry/persistentvolumes/pvc-815e2ef3-d270-4fc8-9c44-8e4047d7408b; range_end:; response_count:1; response_revision:5369; }","duration":"152.360842ms","start":"2026-08-19T09:21:08.381191Z","end":"2026-08-19T09:21:08.533552Z","steps":["trace[1949973040] 'agreement among raft nodes before linearized reading' (duration: 152.083727ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:21:14.657037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.276194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:21:14.657291Z","caller":"traceutil/trace.go:171","msg":"trace[1672623687] transaction","detail":"{read_only:false; response_revision:5522; number_of_response:1; }","duration":"423.155953ms","start":"2026-08-19T09:21:14.234075Z","end":"2026-08-19T09:21:14.657231Z","steps":["trace[1672623687] 'process raft request' (duration: 102.549283ms)","trace[1672623687] 'compare' (duration: 320.08106ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:21:14.65748Z","caller":"traceutil/trace.go:171","msg":"trace[1665639206] linearizableReadLoop","detail":"{readStateIndex:5727; appliedIndex:5726; }","duration":"246.386741ms","start":"2026-08-19T09:21:14.41105Z","end":"2026-08-19T09:21:14.657437Z","steps":["trace[1665639206] 'read index received' (duration: 64.931µs)","trace[1665639206] 'applied index is now lower than readState.Index' (duration: 246.31772ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:21:14.657535Z","caller":"traceutil/trace.go:171","msg":"trace[2249198] transaction","detail":"{read_only:false; response_revision:5523; number_of_response:1; }","duration":"101.210747ms","start":"2026-08-19T09:21:14.556279Z","end":"2026-08-19T09:21:14.65749Z","steps":["trace[2249198] 'process raft request' (duration: 100.926422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:21:14.65756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:21:14.234037Z","time spent":"423.384287ms","remote":"127.0.0.1:55120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:21:14.657725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.640437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a631546e-7350-442a-9610-8b89610d826c\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-19T09:21:14.657829Z","caller":"traceutil/trace.go:171","msg":"trace[1723500096] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a631546e-7350-442a-9610-8b89610d826c; range_end:; response_count:1; response_revision:5523; }","duration":"246.777599ms","start":"2026-08-19T09:21:14.411028Z","end":"2026-08-19T09:21:14.657805Z","steps":["trace[1723500096] 'agreement among raft nodes before linearized reading' (duration: 246.517664ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:21:14.836077Z","caller":"traceutil/trace.go:171","msg":"trace[1578800399] linearizableReadLoop","detail":"{readStateIndex:5729; appliedIndex:5728; }","duration":"178.466214ms","start":"2026-08-19T09:21:14.657575Z","end":"2026-08-19T09:21:14.836041Z","steps":["trace[1578800399] 'read index received' (duration: 116.688396ms)","trace[1578800399] 'applied index is now lower than readState.Index' (duration: 61.776568ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:21:14.836146Z","caller":"traceutil/trace.go:171","msg":"trace[1737898411] transaction","detail":"{read_only:false; response_revision:5524; number_of_response:1; }","duration":"224.99829ms","start":"2026-08-19T09:21:14.611105Z","end":"2026-08-19T09:21:14.836103Z","steps":["trace[1737898411] 'process raft request' (duration: 163.156541ms)","trace[1737898411] 'compare' (duration: 61.611745ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:21:14.836424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.971088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a631546e-7350-442a-9610-8b89610d826c\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-08-19T09:21:14.836405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.320896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:21:14.836496Z","caller":"traceutil/trace.go:171","msg":"trace[1956342375] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a631546e-7350-442a-9610-8b89610d826c; range_end:; response_count:1; response_revision:5524; }","duration":"425.09842ms","start":"2026-08-19T09:21:14.411377Z","end":"2026-08-19T09:21:14.836476Z","steps":["trace[1956342375] 'agreement among raft nodes before linearized reading' (duration: 424.790764ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:21:14.836525Z","caller":"traceutil/trace.go:171","msg":"trace[489128525] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:5524; }","duration":"291.51744ms","start":"2026-08-19T09:21:14.544978Z","end":"2026-08-19T09:21:14.836495Z","steps":["trace[489128525] 'agreement among raft nodes before linearized reading' (duration: 291.161643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:21:14.836559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:21:14.411367Z","time spent":"425.177402ms","remote":"127.0.0.1:55040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3670,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a631546e-7350-442a-9610-8b89610d826c\" "} {"level":"warn","ts":"2026-08-19T09:21:14.836729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.398826ms","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-08-19T09:21:14.836832Z","caller":"traceutil/trace.go:171","msg":"trace[438559639] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5524; }","duration":"266.565019ms","start":"2026-08-19T09:21:14.570244Z","end":"2026-08-19T09:21:14.836809Z","steps":["trace[438559639] 'agreement among raft nodes before linearized reading' (duration: 265.866865ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:21:24.785743Z","caller":"traceutil/trace.go:171","msg":"trace[809870423] transaction","detail":"{read_only:false; response_revision:5626; number_of_response:1; }","duration":"133.431237ms","start":"2026-08-19T09:21:24.652281Z","end":"2026-08-19T09:21:24.785712Z","steps":["trace[809870423] 'process raft request' (duration: 126.202439ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:21:24.786172Z","caller":"traceutil/trace.go:171","msg":"trace[2100126159] transaction","detail":"{read_only:false; response_revision:5628; number_of_response:1; }","duration":"133.429607ms","start":"2026-08-19T09:21:24.6527Z","end":"2026-08-19T09:21:24.78613Z","steps":["trace[2100126159] 'process raft request' (duration: 132.945648ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:21:24.786172Z","caller":"traceutil/trace.go:171","msg":"trace[1280441569] transaction","detail":"{read_only:false; response_revision:5627; number_of_response:1; }","duration":"133.650911ms","start":"2026-08-19T09:21:24.65247Z","end":"2026-08-19T09:21:24.786121Z","steps":["trace[1280441569] 'process raft request' (duration: 133.128731ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:22:05.492508Z","caller":"traceutil/trace.go:171","msg":"trace[31540299] transaction","detail":"{read_only:false; response_revision:5977; number_of_response:1; }","duration":"100.13177ms","start":"2026-08-19T09:22:05.392333Z","end":"2026-08-19T09:22:05.492464Z","steps":["trace[31540299] 'process raft request' (duration: 99.673972ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:22:18.777763Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3001} {"level":"info","ts":"2026-08-19T09:22:18.866373Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3001,"took":"86.817733ms","hash":3483237800,"current-db-size-bytes":57622528,"current-db-size":"58 MB","current-db-size-in-use-bytes":31748096,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-19T09:22:18.86646Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3483237800,"revision":3001,"compact-revision":-1} {"level":"info","ts":"2026-08-19T09:27:13.102361Z","caller":"traceutil/trace.go:171","msg":"trace[34245310] transaction","detail":"{read_only:false; response_revision:8263; number_of_response:1; }","duration":"282.766975ms","start":"2026-08-19T09:27:12.819551Z","end":"2026-08-19T09:27:13.102318Z","steps":["trace[34245310] 'process raft request' (duration: 282.575122ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:27:18.78944Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6075} {"level":"info","ts":"2026-08-19T09:27:18.884536Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6075,"took":"93.061739ms","hash":1879619285,"current-db-size-bytes":57622528,"current-db-size":"58 MB","current-db-size-in-use-bytes":23711744,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-19T09:27:18.884655Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1879619285,"revision":6075,"compact-revision":3001} {"level":"info","ts":"2026-08-19T09:27:53.49068Z","caller":"traceutil/trace.go:171","msg":"trace[586991737] linearizableReadLoop","detail":"{readStateIndex:8833; appliedIndex:8832; }","duration":"176.334839ms","start":"2026-08-19T09:27:53.314319Z","end":"2026-08-19T09:27:53.490654Z","steps":["trace[586991737] 'read index received' (duration: 176.075495ms)","trace[586991737] 'applied index is now lower than readState.Index' (duration: 258.294µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:27:53.491068Z","caller":"traceutil/trace.go:171","msg":"trace[1007006235] transaction","detail":"{read_only:false; response_revision:8531; number_of_response:1; }","duration":"189.551814ms","start":"2026-08-19T09:27:53.301501Z","end":"2026-08-19T09:27:53.491053Z","steps":["trace[1007006235] 'process raft request' (duration: 188.978233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:27:53.491443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.103984ms","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-08-19T09:27:53.491492Z","caller":"traceutil/trace.go:171","msg":"trace[1203221684] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8531; }","duration":"177.193756ms","start":"2026-08-19T09:27:53.314287Z","end":"2026-08-19T09:27:53.491481Z","steps":["trace[1203221684] 'agreement among raft nodes before linearized reading' (duration: 177.029253ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:27:53.670975Z","caller":"traceutil/trace.go:171","msg":"trace[1528286040] transaction","detail":"{read_only:false; response_revision:8532; number_of_response:1; }","duration":"174.369701ms","start":"2026-08-19T09:27:53.496571Z","end":"2026-08-19T09:27:53.67094Z","steps":["trace[1528286040] 'process raft request' (duration: 78.455828ms)","trace[1528286040] 'compare' (duration: 95.659728ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:27:53.671174Z","caller":"traceutil/trace.go:171","msg":"trace[1028242794] linearizableReadLoop","detail":"{readStateIndex:8835; appliedIndex:8833; }","duration":"116.205923ms","start":"2026-08-19T09:27:53.554937Z","end":"2026-08-19T09:27:53.671143Z","steps":["trace[1028242794] 'read index received' (duration: 20.103696ms)","trace[1028242794] 'applied index is now lower than readState.Index' (duration: 96.100677ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:27:53.671329Z","caller":"traceutil/trace.go:171","msg":"trace[152139011] transaction","detail":"{read_only:false; response_revision:8533; number_of_response:1; }","duration":"174.589855ms","start":"2026-08-19T09:27:53.496726Z","end":"2026-08-19T09:27:53.671316Z","steps":["trace[152139011] 'process raft request' (duration: 174.144977ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:27:53.671514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.558931ms","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-08-19T09:27:53.671583Z","caller":"traceutil/trace.go:171","msg":"trace[1119864823] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8533; }","duration":"116.650403ms","start":"2026-08-19T09:27:53.554917Z","end":"2026-08-19T09:27:53.671567Z","steps":["trace[1119864823] 'agreement among raft nodes before linearized reading' (duration: 116.52694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:27:53.671413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.857101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:27:53.671656Z","caller":"traceutil/trace.go:171","msg":"trace[933376688] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:8533; }","duration":"109.162388ms","start":"2026-08-19T09:27:53.562478Z","end":"2026-08-19T09:27:53.671641Z","steps":["trace[933376688] 'agreement among raft nodes before linearized reading' (duration: 108.874472ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:28:05.895484Z","caller":"traceutil/trace.go:171","msg":"trace[507445054] linearizableReadLoop","detail":"{readStateIndex:8942; appliedIndex:8940; }","duration":"237.870212ms","start":"2026-08-19T09:28:05.657592Z","end":"2026-08-19T09:28:05.895462Z","steps":["trace[507445054] 'read index received' (duration: 34.654026ms)","trace[507445054] 'applied index is now lower than readState.Index' (duration: 203.215016ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:28:05.895549Z","caller":"traceutil/trace.go:171","msg":"trace[1668053368] transaction","detail":"{read_only:false; response_revision:8635; number_of_response:1; }","duration":"239.394281ms","start":"2026-08-19T09:28:05.656117Z","end":"2026-08-19T09:28:05.895512Z","steps":["trace[1668053368] 'process raft request' (duration: 239.203817ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:28:05.895617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.998295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:28:05.89565Z","caller":"traceutil/trace.go:171","msg":"trace[1006326948] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:8635; }","duration":"238.060956ms","start":"2026-08-19T09:28:05.657577Z","end":"2026-08-19T09:28:05.895638Z","steps":["trace[1006326948] 'agreement among raft nodes before linearized reading' (duration: 237.990655ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:28:05.895872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.523918ms","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-08-19T09:28:05.895914Z","caller":"traceutil/trace.go:171","msg":"trace[1560880157] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8635; }","duration":"169.591589ms","start":"2026-08-19T09:28:05.726313Z","end":"2026-08-19T09:28:05.895905Z","steps":["trace[1560880157] 'agreement among raft nodes before linearized reading' (duration: 169.386955ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:28:05.896183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.091712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-5ffcd4c84c-n4kkg.18cd2abeb4153f2f\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-08-19T09:28:05.896222Z","caller":"traceutil/trace.go:171","msg":"trace[2097701025] range","detail":"{range_begin:/registry/events/openstack/barbican-api-5ffcd4c84c-n4kkg.18cd2abeb4153f2f; range_end:; response_count:1; response_revision:8635; }","duration":"205.149883ms","start":"2026-08-19T09:28:05.691063Z","end":"2026-08-19T09:28:05.896213Z","steps":["trace[2097701025] 'agreement among raft nodes before linearized reading' (duration: 204.567212ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:28:05.895983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.132871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:28:05.898425Z","caller":"traceutil/trace.go:171","msg":"trace[731558773] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:8635; }","duration":"107.653949ms","start":"2026-08-19T09:28:05.790734Z","end":"2026-08-19T09:28:05.898388Z","steps":["trace[731558773] 'agreement among raft nodes before linearized reading' (duration: 104.936877ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:28:06.53118Z","caller":"traceutil/trace.go:171","msg":"trace[398057555] transaction","detail":"{read_only:false; response_revision:8645; number_of_response:1; }","duration":"145.783431ms","start":"2026-08-19T09:28:06.385375Z","end":"2026-08-19T09:28:06.531158Z","steps":["trace[398057555] 'process raft request' (duration: 145.6941ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:28:06.532281Z","caller":"traceutil/trace.go:171","msg":"trace[1066990816] linearizableReadLoop","detail":"{readStateIndex:8953; appliedIndex:8952; }","duration":"118.926225ms","start":"2026-08-19T09:28:06.413327Z","end":"2026-08-19T09:28:06.532253Z","steps":["trace[1066990816] 'read index received' (duration: 117.823354ms)","trace[1066990816] 'applied index is now lower than readState.Index' (duration: 1.101771ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:28:06.532558Z","caller":"traceutil/trace.go:171","msg":"trace[1566886938] transaction","detail":"{read_only:false; response_revision:8646; number_of_response:1; }","duration":"119.16763ms","start":"2026-08-19T09:28:06.41322Z","end":"2026-08-19T09:28:06.532388Z","steps":["trace[1566886938] 'process raft request' (duration: 118.815083ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:28:06.532691Z","caller":"traceutil/trace.go:171","msg":"trace[1528501416] transaction","detail":"{read_only:false; response_revision:8647; number_of_response:1; }","duration":"103.285245ms","start":"2026-08-19T09:28:06.429193Z","end":"2026-08-19T09:28:06.532478Z","steps":["trace[1528501416] 'process raft request' (duration: 103.007679ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:28:06.532775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.416855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228483"} {"level":"info","ts":"2026-08-19T09:28:06.532836Z","caller":"traceutil/trace.go:171","msg":"trace[520764973] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8647; }","duration":"119.514748ms","start":"2026-08-19T09:28:06.413303Z","end":"2026-08-19T09:28:06.532818Z","steps":["trace[520764973] 'agreement among raft nodes before linearized reading' (duration: 119.057638ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:28:07.686238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.428254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-08-19T09:28:07.686355Z","caller":"traceutil/trace.go:171","msg":"trace[2115005654] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:8650; }","duration":"171.618758ms","start":"2026-08-19T09:28:07.514707Z","end":"2026-08-19T09:28:07.686325Z","steps":["trace[2115005654] 'range keys from in-memory index tree' (duration: 171.226191ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:28:07.686382Z","caller":"traceutil/trace.go:171","msg":"trace[873504401] transaction","detail":"{read_only:false; response_revision:8651; number_of_response:1; }","duration":"140.802626ms","start":"2026-08-19T09:28:07.545549Z","end":"2026-08-19T09:28:07.686352Z","steps":["trace[873504401] 'process raft request' (duration: 140.354427ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:28:22.107569Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023c2.wal"} {"level":"info","ts":"2026-08-19T09:29:57.995187Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f3d52791b64f7591","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-19T09:29:58.007024Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-19T09:29:58.007156Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-19T09:29:59.043567Z","caller":"traceutil/trace.go:171","msg":"trace[821001571] transaction","detail":"{read_only:false; response_revision:9680; number_of_response:1; }","duration":"115.591502ms","start":"2026-08-19T09:29:58.927933Z","end":"2026-08-19T09:29:59.043524Z","steps":["trace[821001571] 'process raft request' (duration: 15.850905ms)","trace[821001571] 'compare' (duration: 99.317049ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:29:59.04372Z","caller":"traceutil/trace.go:171","msg":"trace[137239588] transaction","detail":"{read_only:false; response_revision:9681; number_of_response:1; }","duration":"113.89194ms","start":"2026-08-19T09:29:58.929798Z","end":"2026-08-19T09:29:59.04369Z","steps":["trace[137239588] 'process raft request' (duration: 113.472231ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:32:18.79607Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8295} {"level":"info","ts":"2026-08-19T09:32:18.877587Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8295,"took":"78.69435ms","hash":2630250572,"current-db-size-bytes":57622528,"current-db-size":"58 MB","current-db-size-in-use-bytes":26705920,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-19T09:32:18.877757Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2630250572,"revision":8295,"compact-revision":6075} {"level":"info","ts":"2026-08-19T09:32:58.935106Z","caller":"traceutil/trace.go:171","msg":"trace[1032141004] transaction","detail":"{read_only:false; response_revision:11232; number_of_response:1; }","duration":"142.739276ms","start":"2026-08-19T09:32:58.792332Z","end":"2026-08-19T09:32:58.935072Z","steps":["trace[1032141004] 'process raft request' (duration: 142.471241ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:32:59.231006Z","caller":"traceutil/trace.go:171","msg":"trace[1440921985] transaction","detail":"{read_only:false; response_revision:11233; number_of_response:1; }","duration":"109.905247ms","start":"2026-08-19T09:32:59.121048Z","end":"2026-08-19T09:32:59.230953Z","steps":["trace[1440921985] 'process raft request' (duration: 109.601211ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:37:18.804251Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10818} {"level":"info","ts":"2026-08-19T09:37:18.881361Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10818,"took":"74.09911ms","hash":3748842201,"current-db-size-bytes":57622528,"current-db-size":"58 MB","current-db-size-in-use-bytes":28033024,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-19T09:37:18.881449Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3748842201,"revision":10818,"compact-revision":8295} {"level":"info","ts":"2026-08-19T09:39:24.052641Z","caller":"traceutil/trace.go:171","msg":"trace[1517357540] transaction","detail":"{read_only:false; response_revision:13825; number_of_response:1; }","duration":"225.290731ms","start":"2026-08-19T09:39:23.827321Z","end":"2026-08-19T09:39:24.052612Z","steps":["trace[1517357540] 'process raft request' (duration: 225.128618ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:39:45.094937Z","caller":"traceutil/trace.go:171","msg":"trace[1478357696] transaction","detail":"{read_only:false; response_revision:14127; number_of_response:1; }","duration":"254.084314ms","start":"2026-08-19T09:39:44.840792Z","end":"2026-08-19T09:39:45.094876Z","steps":["trace[1478357696] 'process raft request' (duration: 253.807099ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:40:51.302754Z","caller":"traceutil/trace.go:171","msg":"trace[87556026] linearizableReadLoop","detail":"{readStateIndex:15334; appliedIndex:15333; }","duration":"489.870227ms","start":"2026-08-19T09:40:50.812857Z","end":"2026-08-19T09:40:51.302728Z","steps":["trace[87556026] 'read index received' (duration: 489.393998ms)","trace[87556026] 'applied index is now lower than readState.Index' (duration: 475.319µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:40:51.302944Z","caller":"traceutil/trace.go:171","msg":"trace[1671795522] transaction","detail":"{read_only:false; response_revision:14832; number_of_response:1; }","duration":"510.896541ms","start":"2026-08-19T09:40:50.791982Z","end":"2026-08-19T09:40:51.302878Z","steps":["trace[1671795522] 'process raft request' (duration: 510.420132ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:40:51.30309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.540341ms","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-08-19T09:40:51.303189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.05297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-08-19T09:40:51.303231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.997597ms","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-08-19T09:40:51.303236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.342297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-682s2\" ","response":"range_response_count:1 size:20659"} {"level":"warn","ts":"2026-08-19T09:40:51.303272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.172061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-08-19T09:40:51.303313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.971934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-08-19T09:40:51.303333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.832731ms","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-08-19T09:40:51.303096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:40:50.791961Z","time spent":"511.041183ms","remote":"127.0.0.1:45596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":416621,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:40:51.303349Z","caller":"traceutil/trace.go:171","msg":"trace[754900655] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14832; }","duration":"126.270172ms","start":"2026-08-19T09:40:51.177058Z","end":"2026-08-19T09:40:51.303328Z","steps":["trace[754900655] 'agreement among raft nodes before linearized reading' (duration: 126.052768ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:40:51.303343Z","caller":"traceutil/trace.go:171","msg":"trace[800662084] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-682s2; range_end:; response_count:1; response_revision:14832; }","duration":"490.498379ms","start":"2026-08-19T09:40:50.81282Z","end":"2026-08-19T09:40:51.303319Z","steps":["trace[800662084] 'agreement among raft nodes before linearized reading' (duration: 490.195583ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:40:51.303359Z","caller":"traceutil/trace.go:171","msg":"trace[1090123841] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14832; }","duration":"473.032285ms","start":"2026-08-19T09:40:50.830314Z","end":"2026-08-19T09:40:51.303347Z","steps":["trace[1090123841] 'agreement among raft nodes before linearized reading' (duration: 472.912642ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:40:51.30338Z","caller":"traceutil/trace.go:171","msg":"trace[669364829] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14832; }","duration":"337.899683ms","start":"2026-08-19T09:40:50.965458Z","end":"2026-08-19T09:40:51.303358Z","steps":["trace[669364829] 'agreement among raft nodes before linearized reading' (duration: 337.857042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:40:51.303421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.76049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-08-19T09:40:51.303418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:40:50.830308Z","time spent":"473.100166ms","remote":"127.0.0.1:55086","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-08-19T09:40:51.303427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:40:50.965436Z","time spent":"337.984434ms","remote":"127.0.0.1:55086","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-08-19T09:40:51.303442Z","caller":"traceutil/trace.go:171","msg":"trace[2113931126] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14832; }","duration":"472.79393ms","start":"2026-08-19T09:40:50.830643Z","end":"2026-08-19T09:40:51.303437Z","steps":["trace[2113931126] 'agreement among raft nodes before linearized reading' (duration: 472.600617ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:40:51.303421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:40:50.812804Z","time spent":"490.599791ms","remote":"127.0.0.1:55040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20683,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-682s2\" "} {"level":"warn","ts":"2026-08-19T09:40:51.303459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:40:50.830638Z","time spent":"472.817151ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":692,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-08-19T09:40:51.303472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.223743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-08-19T09:40:51.30346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.307773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-08-19T09:40:51.303544Z","caller":"traceutil/trace.go:171","msg":"trace[1035183810] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14832; }","duration":"197.347666ms","start":"2026-08-19T09:40:51.106172Z","end":"2026-08-19T09:40:51.303519Z","steps":["trace[1035183810] 'agreement among raft nodes before linearized reading' (duration: 197.134462ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:40:51.303559Z","caller":"traceutil/trace.go:171","msg":"trace[782017436] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14832; }","duration":"126.456246ms","start":"2026-08-19T09:40:51.177078Z","end":"2026-08-19T09:40:51.303535Z","steps":["trace[782017436] 'agreement among raft nodes before linearized reading' (duration: 126.1152ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:40:51.303563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.72425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-08-19T09:40:51.303296Z","caller":"traceutil/trace.go:171","msg":"trace[240086021] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14832; }","duration":"126.083759ms","start":"2026-08-19T09:40:51.177194Z","end":"2026-08-19T09:40:51.303278Z","steps":["trace[240086021] 'agreement among raft nodes before linearized reading' (duration: 125.947687ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:40:51.303602Z","caller":"traceutil/trace.go:171","msg":"trace[656810123] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14832; }","duration":"138.799672ms","start":"2026-08-19T09:40:51.164792Z","end":"2026-08-19T09:40:51.303592Z","steps":["trace[656810123] 'agreement among raft nodes before linearized reading' (duration: 138.480766ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:40:51.303248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.324466ms","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-08-19T09:40:51.303656Z","caller":"traceutil/trace.go:171","msg":"trace[982339976] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14832; }","duration":"197.768043ms","start":"2026-08-19T09:40:51.105882Z","end":"2026-08-19T09:40:51.30365Z","steps":["trace[982339976] 'agreement among raft nodes before linearized reading' (duration: 197.347875ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:40:51.303296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.694121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:40:51.303631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.089869ms","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-08-19T09:40:51.3037Z","caller":"traceutil/trace.go:171","msg":"trace[1999812889] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14832; }","duration":"140.129838ms","start":"2026-08-19T09:40:51.163558Z","end":"2026-08-19T09:40:51.303688Z","steps":["trace[1999812889] 'agreement among raft nodes before linearized reading' (duration: 139.72433ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:40:51.303745Z","caller":"traceutil/trace.go:171","msg":"trace[1912907269] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14832; }","duration":"334.252811ms","start":"2026-08-19T09:40:50.969463Z","end":"2026-08-19T09:40:51.303716Z","steps":["trace[1912907269] 'agreement among raft nodes before linearized reading' (duration: 333.807763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:40:51.303193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.484285ms","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-08-19T09:40:51.303796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:40:50.96945Z","time spent":"334.337933ms","remote":"127.0.0.1:55086","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":"info","ts":"2026-08-19T09:40:51.303799Z","caller":"traceutil/trace.go:171","msg":"trace[244494752] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14832; }","duration":"337.144907ms","start":"2026-08-19T09:40:50.966644Z","end":"2026-08-19T09:40:51.303789Z","steps":["trace[244494752] 'agreement among raft nodes before linearized reading' (duration: 336.462634ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:40:51.303901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:40:50.966625Z","time spent":"337.26782ms","remote":"127.0.0.1:55086","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":"info","ts":"2026-08-19T09:40:51.303194Z","caller":"traceutil/trace.go:171","msg":"trace[374266617] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14832; }","duration":"476.662333ms","start":"2026-08-19T09:40:50.826508Z","end":"2026-08-19T09:40:51.30317Z","steps":["trace[374266617] 'agreement among raft nodes before linearized reading' (duration: 476.5034ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:40:51.30397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:40:50.8265Z","time spent":"477.457108ms","remote":"127.0.0.1:55086","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-08-19T09:40:51.303284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.348042ms","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-08-19T09:40:51.304048Z","caller":"traceutil/trace.go:171","msg":"trace[1985606613] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14832; }","duration":"337.130987ms","start":"2026-08-19T09:40:50.966906Z","end":"2026-08-19T09:40:51.304037Z","steps":["trace[1985606613] 'agreement among raft nodes before linearized reading' (duration: 336.361242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:40:51.304108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:40:50.966866Z","time spent":"337.217228ms","remote":"127.0.0.1:55086","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":"info","ts":"2026-08-19T09:40:51.303256Z","caller":"traceutil/trace.go:171","msg":"trace[2038698758] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14832; }","duration":"197.145391ms","start":"2026-08-19T09:40:51.106091Z","end":"2026-08-19T09:40:51.303236Z","steps":["trace[2038698758] 'agreement among raft nodes before linearized reading' (duration: 196.954908ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:42:18.810757Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12977} {"level":"info","ts":"2026-08-19T09:42:18.885059Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12977,"took":"72.210035ms","hash":1327073815,"current-db-size-bytes":57622528,"current-db-size":"58 MB","current-db-size-in-use-bytes":30310400,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-19T09:42:18.885207Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1327073815,"revision":12977,"compact-revision":10818} {"level":"warn","ts":"2026-08-19T09:46:29.329877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.008044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T09:46:29.330072Z","caller":"traceutil/trace.go:171","msg":"trace[53503262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17368; }","duration":"166.28748ms","start":"2026-08-19T09:46:29.163758Z","end":"2026-08-19T09:46:29.330046Z","steps":["trace[53503262] 'range keys from in-memory index tree' (duration: 165.867931ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:47:18.821022Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15384} {"level":"info","ts":"2026-08-19T09:47:18.908079Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15384,"took":"84.093003ms","hash":3576078010,"current-db-size-bytes":57622528,"current-db-size":"58 MB","current-db-size-in-use-bytes":33443840,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-19T09:47:18.908144Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3576078010,"revision":15384,"compact-revision":12977} {"level":"info","ts":"2026-08-19T09:50:22.917938Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f3d52791b64f7591","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-19T09:50:22.925996Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-19T09:50:22.926076Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-19T09:51:45.541548Z","caller":"traceutil/trace.go:171","msg":"trace[1888431092] transaction","detail":"{read_only:false; response_revision:19891; number_of_response:1; }","duration":"163.955886ms","start":"2026-08-19T09:51:45.377553Z","end":"2026-08-19T09:51:45.541509Z","steps":["trace[1888431092] 'process raft request' (duration: 163.839084ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:51:45.541597Z","caller":"traceutil/trace.go:171","msg":"trace[374023918] transaction","detail":"{read_only:false; response_revision:19890; number_of_response:1; }","duration":"164.031217ms","start":"2026-08-19T09:51:45.377517Z","end":"2026-08-19T09:51:45.541548Z","steps":["trace[374023918] 'process raft request' (duration: 145.787537ms)","trace[374023918] 'compare' (duration: 17.826203ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:51:45.541682Z","caller":"traceutil/trace.go:171","msg":"trace[13662953] transaction","detail":"{read_only:false; response_revision:19892; number_of_response:1; }","duration":"164.119489ms","start":"2026-08-19T09:51:45.377545Z","end":"2026-08-19T09:51:45.541665Z","steps":["trace[13662953] 'process raft request' (duration: 163.901855ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:51:51.790229Z","caller":"traceutil/trace.go:171","msg":"trace[58455559] transaction","detail":"{read_only:false; response_revision:19920; number_of_response:1; }","duration":"211.489967ms","start":"2026-08-19T09:51:51.578709Z","end":"2026-08-19T09:51:51.790199Z","steps":["trace[58455559] 'process raft request' (duration: 211.253352ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:51:51.796528Z","caller":"traceutil/trace.go:171","msg":"trace[1751136136] transaction","detail":"{read_only:false; response_revision:19922; number_of_response:1; }","duration":"216.650646ms","start":"2026-08-19T09:51:51.579855Z","end":"2026-08-19T09:51:51.796506Z","steps":["trace[1751136136] 'process raft request' (duration: 216.611716ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:51:51.796612Z","caller":"traceutil/trace.go:171","msg":"trace[1628883849] transaction","detail":"{read_only:false; response_revision:19921; number_of_response:1; }","duration":"217.247238ms","start":"2026-08-19T09:51:51.57934Z","end":"2026-08-19T09:51:51.796587Z","steps":["trace[1628883849] 'process raft request' (duration: 216.982773ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:51:52.099408Z","caller":"traceutil/trace.go:171","msg":"trace[1430591577] linearizableReadLoop","detail":"{readStateIndex:20592; appliedIndex:20591; }","duration":"146.050521ms","start":"2026-08-19T09:51:51.953328Z","end":"2026-08-19T09:51:52.099378Z","steps":["trace[1430591577] 'read index received' (duration: 102.262511ms)","trace[1430591577] 'applied index is now lower than readState.Index' (duration: 43.78704ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:51:52.099443Z","caller":"traceutil/trace.go:171","msg":"trace[1234023664] transaction","detail":"{read_only:false; response_revision:19923; number_of_response:1; }","duration":"155.024714ms","start":"2026-08-19T09:51:51.94438Z","end":"2026-08-19T09:51:52.099405Z","steps":["trace[1234023664] 'process raft request' (duration: 111.134161ms)","trace[1234023664] 'compare' (duration: 43.7614ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:51:52.099616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.284126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-19T09:51:52.099696Z","caller":"traceutil/trace.go:171","msg":"trace[1676989157] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:19923; }","duration":"146.414599ms","start":"2026-08-19T09:51:51.953253Z","end":"2026-08-19T09:51:52.099668Z","steps":["trace[1676989157] 'agreement among raft nodes before linearized reading' (duration: 146.225825ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:51:52.132427Z","caller":"traceutil/trace.go:171","msg":"trace[367136605] transaction","detail":"{read_only:false; response_revision:19924; number_of_response:1; }","duration":"163.628949ms","start":"2026-08-19T09:51:51.968776Z","end":"2026-08-19T09:51:52.132405Z","steps":["trace[367136605] 'process raft request' (duration: 163.505567ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:51:52.447401Z","caller":"traceutil/trace.go:171","msg":"trace[1151403538] transaction","detail":"{read_only:false; response_revision:19926; number_of_response:1; }","duration":"109.521451ms","start":"2026-08-19T09:51:52.3378Z","end":"2026-08-19T09:51:52.447322Z","steps":["trace[1151403538] 'process raft request' (duration: 109.353608ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:52:18.827756Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17956} {"level":"info","ts":"2026-08-19T09:52:18.957571Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17956,"took":"126.418685ms","hash":4025873130,"current-db-size-bytes":57622528,"current-db-size":"58 MB","current-db-size-in-use-bytes":29782016,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-19T09:52:18.957649Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4025873130,"revision":17956,"compact-revision":15384} {"level":"info","ts":"2026-08-19T09:55:34.650322Z","caller":"traceutil/trace.go:171","msg":"trace[119598967] linearizableReadLoop","detail":"{readStateIndex:23044; appliedIndex:23043; }","duration":"249.452555ms","start":"2026-08-19T09:55:34.400849Z","end":"2026-08-19T09:55:34.650302Z","steps":["trace[119598967] 'read index received' (duration: 249.361863ms)","trace[119598967] 'applied index is now lower than readState.Index' (duration: 89.712µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:55:34.650419Z","caller":"traceutil/trace.go:171","msg":"trace[1999013765] transaction","detail":"{read_only:false; response_revision:22302; number_of_response:1; }","duration":"255.549904ms","start":"2026-08-19T09:55:34.394839Z","end":"2026-08-19T09:55:34.650389Z","steps":["trace[1999013765] 'process raft request' (duration: 255.33083ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:55:34.650527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.64667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-08-19T09:55:34.650604Z","caller":"traceutil/trace.go:171","msg":"trace[1124628716] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22302; }","duration":"249.741201ms","start":"2026-08-19T09:55:34.400843Z","end":"2026-08-19T09:55:34.650585Z","steps":["trace[1124628716] 'agreement among raft nodes before linearized reading' (duration: 249.575198ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:57:18.83462Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20144} {"level":"info","ts":"2026-08-19T09:57:18.942239Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20144,"took":"104.419594ms","hash":1618748360,"current-db-size-bytes":57622528,"current-db-size":"58 MB","current-db-size-in-use-bytes":43282432,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-19T09:57:18.942304Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1618748360,"revision":20144,"compact-revision":17956} {"level":"info","ts":"2026-08-19T10:02:18.841369Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23361} {"level":"info","ts":"2026-08-19T10:02:18.979012Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23361,"took":"132.72411ms","hash":2187770098,"current-db-size-bytes":57622528,"current-db-size":"58 MB","current-db-size-in-use-bytes":30859264,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-19T10:02:18.979073Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2187770098,"revision":23361,"compact-revision":20144}